1 Topic by haba_est (edited by haba_est 2018-09-06 19:08:54)

  • haba_est
  • Member
  • Offline
  • Registered: 2018-09-06
  • Posts: 3

Topic: Can´t login to the Sogo webmail after upgrade from the Sogo 3 to 4

==== REQUIRED BASIC INFO OF YOUR IREDMAIL SERVER ====
- iRedMail version (check /etc/iredmail-release): 0.9.8
- Linux/BSD distribution name and version: Ubuntu 16.04 LTS
- Store mail accounts in which backend (LDAP/MySQL/PGSQL): MySQL
- Web server (Apache or Nginx): Apache
- Manage mail accounts with iRedAdmin-Pro? No
- [IMPORTANT] Related original log or error message is required if you're experiencing an issue.
====

Hello, after the upgrade Sogo, I can´t login to the Sogo webmail. After logining to the Sogo webmail, welcome postmaster appears and then re-login page. None of the Sogo users can log in.

Here is sogo log:
Sep 06 06:44:01 sogod [27687]: version 4.0.2 (build @shiva2.inverse 201809050538) -- starting
Sep 06 06:44:01 sogod [27687]: vmem size check enabled: shutting down app when vmem > 384 MB. Currently at 224 MB
Sep 06 06:44:01 sogod [27687]: <0x0x564e0dcc6070[SOGoProductLoader]> SOGo products loaded from '/usr/lib/GNUstep/SOGo':
Sep 06 06:44:01 sogod [27687]: <0x0x564e0dcc6070[SOGoProductLoader]>   MailPartViewers.SOGo, Appointments.SOGo, PreferencesUI.SOGo, Mailer.SOGo, Contacts.SOGo, MainUI.SOGo, AdministrationUI.SOGo, CommonUI.S$
Sep 06 06:44:01 sogod [27687]: All products loaded - current memory usage at 278 MB
Sep 06 06:44:01 sogod [27687]: <0x0x564e0dac6250[WOWatchDog]> listening on 127.0.0.1:20000
Sep 06 06:44:01 sogod [27687]: <0x0x564e0dac6250[WOWatchDog]> watchdog process pid: 27687
Sep 06 06:44:01 sogod [27687]: <0x0x7fb73efa5240[WOWatchDogChild]> watchdog request timeout set to 60 minutes
Sep 06 06:44:01 sogod [27687]: <0x0x564e0dac6250[WOWatchDog]> preparing 10 children
Sep 06 06:44:01 sogod [27687]: <0x0x564e0dac6250[WOWatchDog]> child spawned with pid 27688
Sep 06 06:44:01 sogod [27687]: <0x0x564e0dac6250[WOWatchDog]> child spawned with pid 27689
Sep 06 06:44:01 sogod [27687]: <0x0x564e0dac6250[WOWatchDog]> child spawned with pid 27690
Sep 06 06:44:01 sogod [27687]: <0x0x564e0dac6250[WOWatchDog]> child spawned with pid 27691
Sep 06 06:44:01 sogod [27687]: <0x0x564e0dac6250[WOWatchDog]> child spawned with pid 27692
Sep 06 06:44:01 sogod [27687]: <0x0x564e0dac6250[WOWatchDog]> child spawned with pid 27693
Sep 06 06:44:01 sogod [27687]: <0x0x564e0dac6250[WOWatchDog]> child spawned with pid 27695
Sep 06 06:44:01 sogod [27687]: <0x0x564e0dac6250[WOWatchDog]> child spawned with pid 27697
Sep 06 06:44:01 sogod [27687]: <0x0x564e0dac6250[WOWatchDog]> child spawned with pid 27698
Sep 06 06:44:01 sogod [27687]: <0x0x564e0dac6250[WOWatchDog]> child spawned with pid 27699
2018-09-06 06:44:01.725 sogod[27697] MySQL4 connection established 0x0x564e0dd07940
2018-09-06 06:44:01.725 sogod[27697] MySQL4 channel 0x0x564e0dd823a0 opened (connection=0x0x564e0dd07940,sogo)
2018-09-06 06:44:01.725 sogod[27697] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_user_profile;
2018-09-06 06:44:01.726 sogod[27697] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.726 sogod[27697] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_folder_info;
2018-09-06 06:44:01.726 sogod[27697] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.733 sogod[27697] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_sessions_folder;
2018-09-06 06:44:01.734 sogod[27697] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.734 sogod[27697] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_alarms_folder;
2018-09-06 06:44:01.734 sogod[27697] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
Sep 06 06:44:01 sogod [27697]: <0x0x564e0de162e0[WOHttpAdaptor]> notified the watchdog that we are ready
2018-09-06 06:44:01.737 sogod[27699] MySQL4 connection established 0x0x564e0dd07940
2018-09-06 06:44:01.747 sogod[27699] MySQL4 channel 0x0x564e0debd9b0 opened (connection=0x0x564e0dd07940,sogo)
2018-09-06 06:44:01.747 sogod[27699] <MySQL4Channel[0x0x564e0debd9b0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_user_profile;
2018-09-06 06:44:01.747 sogod[27699] <MySQL4Channel[0x0x564e0debd9b0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.747 sogod[27699] <MySQL4Channel[0x0x564e0debd9b0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_folder_info;
2018-09-06 06:44:01.748 sogod[27699] <MySQL4Channel[0x0x564e0debd9b0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.750 sogod[27699] <MySQL4Channel[0x0x564e0debd9b0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_sessions_folder;
2018-09-06 06:44:01.738 sogod[27698] MySQL4 connection established 0x0x564e0dd07940
2018-09-06 06:44:01.750 sogod[27698] MySQL4 channel 0x0x564e0debd9b0 opened (connection=0x0x564e0dd07940,sogo)
2018-09-06 06:44:01.750 sogod[27698] <MySQL4Channel[0x0x564e0debd9b0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_user_profile;
2018-09-06 06:44:01.751 sogod[27698] <MySQL4Channel[0x0x564e0debd9b0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.751 sogod[27698] <MySQL4Channel[0x0x564e0debd9b0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_folder_info;
2018-09-06 06:44:01.755 sogod[27698] <MySQL4Channel[0x0x564e0debd9b0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.757 sogod[27698] <MySQL4Channel[0x0x564e0debd9b0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_sessions_folder;
2018-09-06 06:44:01.759 sogod[27699] <MySQL4Channel[0x0x564e0debd9b0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.759 sogod[27699] <MySQL4Channel[0x0x564e0debd9b0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_alarms_folder;
2018-09-06 06:44:01.759 sogod[27699] <MySQL4Channel[0x0x564e0debd9b0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
Sep 06 06:44:01 sogod [27699]: <0x0x564e0dcf7880[WOHttpAdaptor]> notified the watchdog that we are ready
2018-09-06 06:44:01.767 sogod[27698] <MySQL4Channel[0x0x564e0debd9b0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.767 sogod[27698] <MySQL4Channel[0x0x564e0debd9b0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_alarms_folder;
2018-09-06 06:44:01.767 sogod[27698] <MySQL4Channel[0x0x564e0debd9b0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
Sep 06 06:44:01 sogod [27698]: <0x0x564e0dcf7880[WOHttpAdaptor]> notified the watchdog that we are ready
2018-09-06 06:44:01.875 sogod[27691] MySQL4 connection established 0x0x564e0dd07940
2018-09-06 06:44:01.910 sogod[27691] MySQL4 channel 0x0x564e0dd823a0 opened (connection=0x0x564e0dd07940,sogo)
2018-09-06 06:44:01.910 sogod[27691] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_user_profile;
2018-09-06 06:44:01.875 sogod[27689] MySQL4 connection established 0x0x564e0dd07940
2018-09-06 06:44:01.911 sogod[27689] MySQL4 channel 0x0x564e0dd823a0 opened (connection=0x0x564e0dd07940,sogo)
2018-09-06 06:44:01.911 sogod[27689] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_user_profile;
2018-09-06 06:44:01.874 sogod[27688] MySQL4 connection established 0x0x564e0dd07940
2018-09-06 06:44:01.912 sogod[27688] MySQL4 channel 0x0x564e0dd823a0 opened (connection=0x0x564e0dd07940,sogo)
2018-09-06 06:44:01.912 sogod[27688] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_user_profile;
2018-09-06 06:44:01.876 sogod[27692] MySQL4 connection established 0x0x564e0dd07940
2018-09-06 06:44:01.912 sogod[27692] MySQL4 channel 0x0x564e0dd823a0 opened (connection=0x0x564e0dd07940,sogo)
2018-09-06 06:44:01.912 sogod[27692] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_user_profile;
2018-09-06 06:44:01.913 sogod[27688] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.913 sogod[27688] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_folder_info;
2018-09-06 06:44:01.913 sogod[27689] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.914 sogod[27689] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_folder_info;
2018-09-06 06:44:01.914 sogod[27691] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.914 sogod[27691] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_folder_info;
2018-09-06 06:44:01.914 sogod[27692] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.914 sogod[27692] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_folder_info;
2018-09-06 06:44:01.915 sogod[27688] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.916 sogod[27689] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.917 sogod[27688] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_sessions_folder;
2018-09-06 06:44:01.917 sogod[27688] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.917 sogod[27688] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_alarms_folder;
2018-09-06 06:44:01.917 sogod[27688] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.918 sogod[27689] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_sessions_folder;
2018-09-06 06:44:01.918 sogod[27691] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
Sep 06 06:44:01 sogod [27688]: <0x0x564e0de162e0[WOHttpAdaptor]> notified the watchdog that we are ready
2018-09-06 06:44:01.920 sogod[27691] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_sessions_folder;
2018-09-06 06:44:01.878 sogod[27693] MySQL4 connection established 0x0x564e0dd07940
2018-09-06 06:44:01.920 sogod[27693] MySQL4 channel 0x0x564e0dd823a0 opened (connection=0x0x564e0dd07940,sogo)
2018-09-06 06:44:01.920 sogod[27693] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_user_profile;
2018-09-06 06:44:01.920 sogod[27689] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.920 sogod[27689] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_alarms_folder;
2018-09-06 06:44:01.920 sogod[27692] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.922 sogod[27692] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_sessions_folder;
2018-09-06 06:44:01.922 sogod[27693] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.922 sogod[27693] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_folder_info;
2018-09-06 06:44:01.922 sogod[27691] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.923 sogod[27691] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_alarms_folder;
2018-09-06 06:44:01.923 sogod[27693] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.924 sogod[27693] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_sessions_folder;
2018-09-06 06:44:01.924 sogod[27692] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.882 sogod[27695] MySQL4 connection established 0x0x564e0dd07940
2018-09-06 06:44:01.925 sogod[27692] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_alarms_folder;
2018-09-06 06:44:01.925 sogod[27689] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.925 sogod[27695] MySQL4 channel 0x0x564e0dd823a0 opened (connection=0x0x564e0dd07940,sogo)
2018-09-06 06:44:01.925 sogod[27695] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_user_profile;
Sep 06 06:44:01 sogod [27689]: <0x0x564e0de162e0[WOHttpAdaptor]> notified the watchdog that we are ready
2018-09-06 06:44:01.925 sogod[27692] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
Sep 06 06:44:01 sogod [27692]: <0x0x564e0de162e0[WOHttpAdaptor]> notified the watchdog that we are ready
2018-09-06 06:44:01.926 sogod[27691] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.926 sogod[27695] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
Sep 06 06:44:01 sogod [27691]: <0x0x564e0de162e0[WOHttpAdaptor]> notified the watchdog that we are ready
2018-09-06 06:44:01.926 sogod[27695] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_folder_info;
2018-09-06 06:44:01.926 sogod[27693] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.926 sogod[27693] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_alarms_folder;
2018-09-06 06:44:01.926 sogod[27695] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.928 sogod[27695] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_sessions_folder;
2018-09-06 06:44:01.928 sogod[27695] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.929 sogod[27695] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_alarms_folder;
2018-09-06 06:44:01.882 sogod[27690] MySQL4 connection established 0x0x564e0dd07940
2018-09-06 06:44:01.929 sogod[27690] MySQL4 channel 0x0x564e0dd823a0 opened (connection=0x0x564e0dd07940,sogo)
2018-09-06 06:44:01.929 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_user_profile;
2018-09-06 06:44:01.929 sogod[27693] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
Sep 06 06:44:01 sogod [27693]: <0x0x564e0de162e0[WOHttpAdaptor]> notified the watchdog that we are ready
2018-09-06 06:44:01.930 sogod[27695] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
Sep 06 06:44:01 sogod [27695]: <0x0x564e0de162e0[WOHttpAdaptor]> notified the watchdog that we are ready
2018-09-06 06:44:01.930 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.931 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_folder_info;
2018-09-06 06:44:01.931 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.933 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_sessions_folder;
2018-09-06 06:44:01.933 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
2018-09-06 06:44:01.933 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT count(*) FROM sogo_alarms_folder;
2018-09-06 06:44:01.933 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
Sep 06 06:44:01 sogod [27690]: <0x0x564e0de162e0[WOHttpAdaptor]> notified the watchdog that we are ready
Sep 06 06:44:40 sogod [27690]: |SOGo| starting method 'GET' on uri '/SOGo/so/postmaster@xxxxx.com/Mail/view'
Sep 06 06:44:40 sogod [27690]: <0x0x564e0de3b4f0[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Sep 06 06:44:40 sogod [27690]: <0x0x564e0de3b4f0[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
2018-09-06 06:44:40.124 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT * FROM users WHERE (c_uid = 'postmaster@xxxxx.com') OR (mail = 'postmaster@xxxxx$
2018-09-06 06:44:40.125 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
Sep 06 06:44:40 sogod [27690]: [WARN] <0x0x7fb73efc6e00[WOxElemBuilder]> could not locate builders: WOxExtElemBuilder,WOxExtElemBuilder
2018-09-06 06:44:40.257 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT c_defaults FROM sogo_user_profile WHERE c_uid = 'anonymous';
2018-09-06 06:44:40.268 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
Sep 06 06:44:40 sogod [27690]: |SOGo| request took 0.350778 seconds to execute
Sep 06 06:44:40 sogod [27690]: 192.168.1.204 "GET /SOGo/so/postmaster@xxxxx.com/Mail/view HTTP/1.1" 200 7310/0 0.399 27042 72% 4M
Sep 06 06:44:48 sogod [27690]: |SOGo| starting method 'GET' on uri '/SOGo'
Sep 06 06:44:48 sogod [27690]: |SOGo| request took 0.004474 seconds to execute
Sep 06 06:44:48 sogod [27690]: 192.168.1.204 "GET /SOGo HTTP/1.1" 302 0/0 0.006 - - 0
Sep 06 06:44:48 sogod [27690]: |SOGo| starting method 'GET' on uri '/SOGo/'
Sep 06 06:44:48 sogod [27690]: |SOGo| request took 0.015383 seconds to execute
Sep 06 06:44:48 sogod [27690]: 192.168.1.204 "GET /SOGo/ HTTP/1.1" 200 7305/0 0.040 27027 72% 32K
Sep 06 06:45:01 sogod [27690]: |SOGo| starting method 'POST' on uri '/SOGo/connect'
2018-09-06 06:45:01.236 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: SELECT c_password FROM users WHERE c_uid = 'postmaster@xxxxx.com';
2018-09-06 06:45:01.236 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has results, entering fetch-mode.
Sep 06 06:45:01 sogod [27690]: SOGoRootPage successful login from '192.168.1.204' for user 'postmaster@xxxxx.com' - expire = -1  grace = -1
2018-09-06 06:45:01.271 sogod[27690] MySQL4 connection established 0x0x564e0e1ea0d0
2018-09-06 06:45:01.291 sogod[27690] MySQL4 channel 0x0x564e0e124930 opened (connection=0x0x564e0e1ea0d0,sogo)
2018-09-06 06:45:01.292 sogod[27690] <MySQL4Channel[0x0x564e0e124930] connection=0x0x564e0e1ea0d0> SQL: BEGIN;
2018-09-06 06:45:01.292 sogod[27690] <MySQL4Channel[0x0x564e0e124930] connection=0x0x564e0e1ea0d0>   query has no results.
2018-09-06 06:45:01.292 sogod[27690] <MySQL4Channel[0x0x564e0e124930] connection=0x0x564e0e1ea0d0> SQL: SELECT t1.c_creationdate, t1.c_id, t1.c_lastseen, t1.c_value FROM  sogo_sessions_folder t1 WHERE t1.c_$
2018-09-06 06:45:01.292 sogod[27690] <MySQL4Channel[0x0x564e0e124930] connection=0x0x564e0e1ea0d0>   query has results, entering fetch-mode.
2018-09-06 06:45:01.293 sogod[27690] <MySQL4Channel[0x0x564e0e124930] connection=0x0x564e0e1ea0d0> SQL: ROLLBACK;
2018-09-06 06:45:01.293 sogod[27690] <MySQL4Channel[0x0x564e0e124930] connection=0x0x564e0e1ea0d0>   query has no results.
2018-09-06 06:45:01.293 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: BEGIN;
2018-09-06 06:45:01.293 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has no results.
2018-09-06 06:45:01.294 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has no results.
2018-09-06 06:45:01.294 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940> SQL: COMMIT;
2018-09-06 06:45:01.386 sogod[27690] <MySQL4Channel[0x0x564e0dd823a0] connection=0x0x564e0dd07940>   query has no results.
Sep 06 06:45:01 sogod [27690]: [WARN] <0x0x564e0e26b260[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table
Sep 06 06:45:01 sogod [27690]: [WARN] <0x0x564e0e26b260[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table
Sep 06 06:45:01 sogod [27690]: [WARN] <0x0x564e0e26b260[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table
2018-09-06 06:45:01.388 sogod[27690] <MySQL4Channel[0x0x564e0e124930] connection=0x0x564e0e1ea0d0> SQL: SELECT c_settings FROM sogo_user_profile WHERE c_uid = 'postmaster@xxxxx.com';
2018-09-06 06:45:01.388 sogod[27690] <MySQL4Channel[0x0x564e0e124930] connection=0x0x564e0e1ea0d0>   query has results, entering fetch-mode.
Sep 06 06:45:01 sogod [27690]: |SOGo| request took 0.154131 seconds to execute
Sep 06 06:45:01 sogod [27690]: 192.168.1.204 "POST /SOGo/connect HTTP/1.1" 200 47/84 0.171 - - 0
Sep 06 06:45:02 sogod [27690]: |SOGo| starting method 'GET' on uri '/SOGo/postmaster@xxxxx.com'
Sep 06 06:45:02 sogod [27690]: |SOGo| request took 0.014951 seconds to execute
Sep 06 06:45:02 sogod [27690]: 192.168.1.204 "GET /SOGo/postmaster@xxxxx.com HTTP/1.1" 200 7308/0 0.017 27033 72% 0

----

Spider Email Archiver: On-Premises, lightweight email archiving software developed by iRedMail team. Supports Amazon S3 compatible storage and custom branding.

2 Reply by ZhangHuangbin

  • ZhangHuangbin
  • ZhangHuangbin
  • iRedMail Developers
  • Offline
  • Registered: 2009-05-06
  • Posts: 30,792

Re: Can´t login to the Sogo webmail after upgrade from the Sogo 3 to 4

haba_est wrote:

Hello, after the upgrade Sogo, I can´t login to the Sogo webmail. After logining to the Sogo webmail, welcome postmaster appears and then re-login page. None of the Sogo users can log in.

I don't see any error in pasted log. And it says:

Sep 06 06:45:01 sogod [27690]: SOGoRootPage successful login from '192.168.1.204' for user 'postmaster@xxxxx.com' - expire = -1  grace = -1

You may need to turn on debug mode in SOGo to get more details:
https://docs.iredmail.org/debug.sogo.html

3 Reply by haba_est

  • haba_est
  • Member
  • Offline
  • Registered: 2018-09-06
  • Posts: 3

Re: Can´t login to the Sogo webmail after upgrade from the Sogo 3 to 4

I am sorry for my english.

I enter a user name and password into the sogo login page, click on the login, the "welcome postmaster" page appears. Then the login page will be displayed again instead of the webmail page.

Here is the log with turn on debug mode in SOGo:

Sep 07 06:30:03 sogod [30246]: <0x0x561fd294dbf0[WOHttpAdaptor]> notified the watchdog that we are ready
Sep 07 06:34:01 sogod [30246]: |SOGo| starting method 'GET' on uri '/SOGo/so/postmaster@xxxxx.com/Mail/view'
Sep 07 06:34:01 sogod [30246]: <0x0x561fd28fc6f0[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Sep 07 06:34:01 sogod [30246]: <0x0x561fd28fc6f0[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
2018-09-07 06:34:02.006 sogod[30246] <MySQL4Channel[0x0x561fd29ed540] connection=0x0x561fd2972ae0> SQL: SELECT * FROM users WHERE (c_uid = 'postmaster@xxxxx.com') OR (mail = 'postmaster@drive-control$
2018-09-07 06:34:02.117 sogod[30246] <MySQL4Channel[0x0x561fd29ed540] connection=0x0x561fd2972ae0>   query has results, entering fetch-mode.
Sep 07 06:34:02 sogod [30246]: [WARN] <0x0x7f5f60bc8e00[WOxElemBuilder]> could not locate builders: WOxExtElemBuilder,WOxExtElemBuilder
2018-09-07 06:34:02.749 sogod[30246] MySQL4 connection established 0x0x561fd2d7fd20
2018-09-07 06:34:02.749 sogod[30246] MySQL4 channel 0x0x561fd2dfdb20 opened (connection=0x0x561fd2d7fd20,sogo)
2018-09-07 06:34:02.749 sogod[30246] <MySQL4Channel[0x0x561fd2dfdb20] connection=0x0x561fd2d7fd20> SQL: SELECT c_defaults FROM sogo_user_profile WHERE c_uid = 'anonymous';
2018-09-07 06:34:02.749 sogod[30246] <MySQL4Channel[0x0x561fd2dfdb20] connection=0x0x561fd2d7fd20>   query has results, entering fetch-mode.
Sep 07 06:34:02 sogod [30246]: |SOGo| request took 1.118909 seconds to execute
Sep 07 06:34:03 sogod [30246]: 192.168.1.204 "GET /SOGo/so/postmaster@xxxxx.com/Mail/view HTTP/1.1" 200 7309/0 1.306 27040 72% 4M
Sep 07 06:34:15 sogod [30246]: |SOGo| starting method 'POST' on uri '/SOGo/connect'
2018-09-07 06:34:15.094 sogod[30246] <MySQL4Channel[0x0x561fd2dfdb20] connection=0x0x561fd2d7fd20> SQL: SELECT c_password FROM users WHERE c_uid = 'postmaster@xxxxx.com';
2018-09-07 06:34:15.095 sogod[30246] <MySQL4Channel[0x0x561fd2dfdb20] connection=0x0x561fd2d7fd20>   query has results, entering fetch-mode.
Sep 07 06:34:15 sogod [30246]: SOGoRootPage successful login from '192.168.1.204' for user 'postmaster@xxxxx.com' - expire = -1  grace = -1
2018-09-07 06:34:15.098 sogod[30246] MySQL4 connection established 0x0x561fd2eda610
2018-09-07 06:34:15.098 sogod[30246] MySQL4 channel 0x0x561fd2e3b9b0 opened (connection=0x0x561fd2eda610,sogo)
2018-09-07 06:34:15.099 sogod[30246] <MySQL4Channel[0x0x561fd2e3b9b0] connection=0x0x561fd2eda610> SQL: BEGIN;
2018-09-07 06:34:15.099 sogod[30246] <MySQL4Channel[0x0x561fd2e3b9b0] connection=0x0x561fd2eda610>   query has no results.
2018-09-07 06:34:15.099 sogod[30246] <MySQL4Channel[0x0x561fd2e3b9b0] connection=0x0x561fd2eda610> SQL: SELECT t1.c_creationdate, t1.c_id, t1.c_lastseen, t1.c_value FROM  sogo_sessions_folder t1 WHERE t1.c_$
2018-09-07 06:34:15.100 sogod[30246] <MySQL4Channel[0x0x561fd2e3b9b0] connection=0x0x561fd2eda610>   query has results, entering fetch-mode.
2018-09-07 06:34:15.100 sogod[30246] <MySQL4Channel[0x0x561fd2e3b9b0] connection=0x0x561fd2eda610> SQL: ROLLBACK;
2018-09-07 06:34:15.100 sogod[30246] <MySQL4Channel[0x0x561fd2e3b9b0] connection=0x0x561fd2eda610>   query has no results.
2018-09-07 06:34:15.100 sogod[30246] <MySQL4Channel[0x0x561fd2dfdb20] connection=0x0x561fd2d7fd20> SQL: BEGIN;
2018-09-07 06:34:15.100 sogod[30246] <MySQL4Channel[0x0x561fd2dfdb20] connection=0x0x561fd2d7fd20>   query has no results.
2018-09-07 06:34:15.100 sogod[30246] <MySQL4Channel[0x0x561fd2dfdb20] connection=0x0x561fd2d7fd20> SQL: INSERT INTO sogo_sessions_folder (c_lastseen, c_creationdate, c_value, c_id) VALUES (1536316455, 15363$
2018-09-07 06:34:15.101 sogod[30246] <MySQL4Channel[0x0x561fd2dfdb20] connection=0x0x561fd2d7fd20>   query has no results.
2018-09-07 06:34:15.101 sogod[30246] <MySQL4Channel[0x0x561fd2dfdb20] connection=0x0x561fd2d7fd20> SQL: COMMIT;
2018-09-07 06:34:15.168 sogod[30246] <MySQL4Channel[0x0x561fd2dfdb20] connection=0x0x561fd2d7fd20>   query has no results.
Sep 07 06:34:15 sogod [30246]: [WARN] <0x0x561fd2ebc940[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table
Sep 07 06:34:15 sogod [30246]: [WARN] <0x0x561fd2ebc940[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table
Sep 07 06:34:15 sogod [30246]: [WARN] <0x0x561fd2ebc940[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table
2018-09-07 06:34:15.169 sogod[30246] <MySQL4Channel[0x0x561fd2e3b9b0] connection=0x0x561fd2eda610> SQL: SELECT c_settings FROM sogo_user_profile WHERE c_uid = 'postmaster@xxxxx.com';
2018-09-07 06:34:15.170 sogod[30246] <MySQL4Channel[0x0x561fd2e3b9b0] connection=0x0x561fd2eda610>   query has results, entering fetch-mode.
Sep 07 06:34:15 sogod [30246]: |SOGo| request took 0.145514 seconds to execute
Sep 07 06:34:15 sogod [30246]: 192.168.1.204 "POST /SOGo/connect HTTP/1.1" 200 47/84 0.189 - - 0
Sep 07 06:34:16 sogod [30246]: |SOGo| starting method 'GET' on uri '/SOGo/so/postmaster@xxxxx.com'
Sep 07 06:34:16 sogod [30246]: |SOGo| request took 0.017475 seconds to execute
Sep 07 06:34:16 sogod [30246]: 192.168.1.204 "GET /SOGo/so/postmaster@xxxxx.com HTTP/1.1" 200 7308/0 0.031 27034 72% 0

4 Reply by ZhangHuangbin

  • ZhangHuangbin
  • ZhangHuangbin
  • iRedMail Developers
  • Offline
  • Registered: 2009-05-06
  • Posts: 30,792

Re: Can´t login to the Sogo webmail after upgrade from the Sogo 3 to 4

No error in sogo log file.

- Did you follow our tutorial to upgrade SOGo? https://docs.iredmail.org/upgrade.sogo.3.to.4.html
- Did you run the script "sql-update-3.2.10_to_4.0.0-mysql.sh" after upgraded SOGo packages?

5 Reply by haba_est

  • haba_est
  • Member
  • Offline
  • Registered: 2018-09-06
  • Posts: 3

Re: Can´t login to the Sogo webmail after upgrade from the Sogo 3 to 4

I found a solution to my problem. When I use address https://192.168.1.253/SOGo instead of address http://192.168.1.253/SOGo, every things is ok. Thank you for your help.