View Issue Details

IDProjectCategoryView StatusLast Update
0004946SOGoWeb Generalpublic2021-03-10 16:39
Reporterballt Assigned Toludovic  
PrioritynormalSeveritymajorReproducibilityalways
Status closedResolutionsuspended 
Platform[Server] LinuxOSRHEL/CentOSOS Version7
Product Version4.3.0 
Summary0004946: Website not working
Description

When I log on to http://192.168.0.1/SOGo I get an "Authentifiziere " (authenticate) for like a minute or so and then "Unbehandelte Fehlerantwort" (unhandled error).
I have the error for the last two weeks. I didn't make any mayor modifications to the server.
Tried changing the settings, reinstalling SOGo, no success.
Also ActiveSync to outlook is slow and unreliable and I also can't access the server from Android using ActiveSync protocol.
I think it is a timeout problem between SOGo and the webserver, but can't find a solution.
I can supply all needed information like logfiles and such...

TagsNo tags attached.

Activities

Christian Mack

Christian Mack

2020-01-31 09:09

developer   ~0014123

Show us your sogo.log entries when logging in.

ballt

ballt

2020-02-02 16:17

reporter   ~0014128

Feb 02 17:15:20 sogod [2503]: [WARN] <0x0x55c6981dec10[WOWatchDogChild]> pid 26947 has been hanging in the same request for 34 minutes
Feb 02 17:15:25 sogod [3033]: <0x0x55c697fa60f0[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Feb 02 17:15:25 sogod [3033]: <0x0x55c697fa60f0[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Feb 02 17:15:25 sogod [3033]: 192.168.0.138 "GET /SOGo/ HTTP/1.1" 200 8924/0 0.074 28834 69% 4M
Feb 02 17:15:37 sogod [2503]: [WARN] <0x0x55c6981a2b80[WOWatchDogChild]> pid 31944 has been hanging in the same request for 6 minutes
Feb 02 17:15:41 sogod [26947]: <0x0x55c6984eb320[SOGoActiveSyncDispatcher]> Sleeping 30 seconds while detecting changes for user ballt in Ping...
Feb 02 17:16:20 sogod [2503]: [WARN] <0x0x55c6981dec10[WOWatchDogChild]> pid 26947 has been hanging in the same request for 35 minutes
Feb 02 17:16:25 sogod [26947]: <0x0x55c6984eb320[SOGoActiveSyncDispatcher]> Sleeping 30 seconds while detecting changes for user ballt in Ping...
Feb 02 17:16:34 sogod [2503]: [WARN] <0x0x55c6981c6820[WOWatchDogChild]> pid 3033 has been hanging in the same request for 1 minutes

Christian Mack

Christian Mack

2020-02-03 12:46

developer   ~0014129

There is no access to any account logged.
Those are mostly sleeping ActiveSync workers, and one access to /SOGo/.
Could you activate SOGoDebugRequests and SoSecurityManagerDebugEnabled in sogo.conf and test again?

ballt

ballt

2020-02-03 13:52

reporter   ~0014130

Tried to activate all Debug Options:
/ 80 Debug /
SOGoDebugRequests = YES;
SoDebugBaseURL = YES;
ImapDebugEnabled = YES;
LDAPDebugEnabled = YES;
PGDebugEnabled = YES;
MySQL4DebugEnabled = YES;
SOGoUIxDebugEnabled = YES;
WODontZipResponse = YES;
SOGoEASDebugEnabled = YES;
WOLogFile = "/var/log/sogo/sogo.log";
SoSecurityManagerDebugEnabled = YES;
}

I am running Nethserver, so I am not shure if changes worked, here the log-file:

Feb 03 14:49:01 sogod [31613]: |SOGo| starting method 'POST' on uri '/SOGo/connect'
Feb 03 14:49:01 sogod [31613]: <0x0x561d73512730[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Feb 03 14:49:01 sogod [31613]: <0x0x561d73512730[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Feb 03 14:49:01 sogod [31613]: <0x0x561d739565f0[NGLdapConnection]> Using ldap_initialize for LDAP URL: ldaps://nsdc-diener.ad.mds4u.local
Feb 03 14:49:36 sogod [31603]: [WARN] <0x0x561d73555b60[WOWatchDogChild]> pid 31611 has been hanging in the same request for 1 minutes
Feb 03 14:49:47 sogod [31608]: |SOGo| starting method 'POST' on uri '/SOGo/Microsoft-Server-ActiveSync?Cmd=Sync&User=ballt&DeviceId=A6BAE3E23A3E495CA55356BA4FB88352&DeviceType=WindowsOutlook15'
Feb 03 14:49:47 sogod [31608]: <0x0x561d73484df0[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Feb 03 14:49:47 sogod [31608]: <0x0x561d73484df0[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Feb 03 14:49:47 sogod [31608]: <0x0x561d734d1630[NGLdapConnection]> Using ldap_initialize for LDAP URL: ldaps://nsdc-diener.ad.mds4u.local
Feb 03 14:49:48 sogod [31610]: |SOGo| starting method 'POST' on uri '/SOGo/Microsoft-Server-ActiveSync?Cmd=SendMail&User=ballt&DeviceId=A6BAE3E23A3E495CA55356BA4FB88352&DeviceType=WindowsOutlook15'
Feb 03 14:49:48 sogod [31610]: <0x0x561d73865490[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Feb 03 14:49:48 sogod [31610]: <0x0x561d73865490[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Feb 03 14:49:48 sogod [31610]: <0x0x561d73663660[NGLdapConnection]> Using ldap_initialize for LDAP URL: ldaps://nsdc-diener.ad.mds4u.local
Feb 03 14:50:01 sogod [31603]: [WARN] <0x0x561d7356db30[WOWatchDogChild]> pid 31613 has been hanging in the same request for 1 minutes

Christian Mack

Christian Mack

2020-02-03 15:48

developer   ~0014131

Debugging is not active.
Try to restart sogo.

ballt

ballt

2020-02-03 16:45

reporter   ~0014133

service sogod restart

Messages reports:
Feb 3 17:22:52 diener systemd: sogod.service stop-sigterm timed out. Killing.
Feb 3 17:22:52 diener systemd: sogod.service: main process exited, code=killed, status=9/KILL
Feb 3 17:22:52 diener systemd: Stopped SOGo is a groupware server.
Feb 3 17:22:52 diener systemd: Unit sogod.service entered failed state.
Feb 3 17:22:52 diener systemd: sogod.service failed.
Feb 3 17:22:52 diener systemd: Starting SOGo is a groupware server...
Feb 3 17:22:52 diener systemd: Started SOGo is a groupware server.

so my sogo doesn't stop correctly

strangely enough seems changes to /etc/sogo/sogo.conf don't work...

these are the settings, but I get no debug information...
SOGoDebugRequests = YES;
SoDebugBaseURL = YES;
ImapDebugEnabled = YES;
LDAPDebugEnabled = YES;
PGDebugEnabled = YES;
MySQL4DebugEnabled = YES;
SOGoUIxDebugEnabled = YES;
WODontZipResponse = YES;
SOGoEASDebugEnabled = YES;
WOLogFile = "/var/log/sogo/sogo.log";

or is this debug information:

Feb 03 17:44:16 sogod [21749]: |SOGo| starting method 'POST' on uri '/SOGo/connect'
Feb 03 17:44:16 sogod [21749]: <0x0x55f400b964a0[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Feb 03 17:44:16 sogod [21749]: <0x0x55f400b964a0[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Feb 03 17:44:16 sogod [21749]: <0x0x55f400f02be0[NGLdapConnection]> Using ldap_initialize for LDAP URL: ldaps://nsdc-diener.ad.mds4u.local
Feb 03 17:44:17 sogod [21740]: [WARN] <0x0x55f400b081d0[WOWatchDogChild]> pid 21746 has been hanging in the same request for 5 minutes
Feb 03 17:44:32 sogod [21740]: [WARN] <0x0x55f400b20a80[WOWatchDogChild]> pid 21751 has been hanging in the same request for 4 minutes
Feb 03 17:44:39 sogod [21740]: [WARN] <0x0x55f400b076a0[WOWatchDogChild]> pid 21744 has been hanging in the same request for 7 minutes
Feb 03 17:44:46 sogod [21740]: [WARN] <0x0x55f400b086b0[WOWatchDogChild]> pid 21748 has been hanging in the same request for 3 minutes
2020-02-03 17:44:47.429 sogod[21751:21751] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search at base '' filter '(objectClass=)' for attrs 'subschemaSubentry'
2020-02-03 17:44:47.430 sogod[21751:21751] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search at base 'CN=Aggregate,CN=Schema,CN=Configuration,DC=ad,DC=mds4u,DC=local' filter '(objectClass=
)' for attrs 'objectclasses'
2020-02-03 17:44:47.444 sogod[21751:21751] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search at base 'dc=ad,dc=mds4u,dc=local' filter '(&(|(sAMAccountName=ballt)(userPrincipalName=ballt))(objectClass=user)(sAMAccountType=805306368))' for attrs 'dn'
Feb 03 17:45:16 sogod [21740]: [WARN] <0x0x55f400b08ab0[WOWatchDogChild]> pid 21749 has been hanging in the same request for 1 minutes
Feb 03 17:45:17 sogod [21740]: [WARN] <0x0x55f400b081d0[WOWatchDogChild]> pid 21746 has been hanging in the same request for 6 minutes

Christian Mack

Christian Mack

2020-02-04 09:05

developer   ~0014134

On stopping you have all ActiveSync workers still running till the set timeout (SOGoInternalSyncInterval + WOWatchDogRequestTimeout) occurs.
Because of that systemd goes in failed state.
You can kill them all, without losing something,.
But that has to be done in systemd, in order to prevent failed state.
Or you have to set systemd to wait for timeout to run out.

The following are some entries from the LDAP debugging log.
Feb 03 17:44:16 sogod [21749]: <0x0x55f400f02be0[NGLdapConnection]> Using ldap_initialize for LDAP URL: ldaps://nsdc-diener.ad.mds4u.local
2020-02-03 17:44:47.429 sogod[21751:21751] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search at base '' filter '(objectClass=)' for attrs 'subschemaSubentry'
2020-02-03 17:44:47.430 sogod[21751:21751] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search at base 'CN=Aggregate,CN=Schema,CN=Configuration,DC=ad,DC=mds4u,DC=local' filter '(objectClass=
)' for attrs 'objectclasses'
2020-02-03 17:44:47.444 sogod[21751:21751] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search at base 'dc=ad,dc=mds4u,dc=local' filter '(&(|(sAMAccountName=ballt)(userPrincipalName=ballt))(objectClass=user)(sAMAccountType=805306368))' for attrs 'dn'

But all others seem missing.
There should be a lot more, as you enabled everything.

ballt

ballt

2020-02-04 14:10

reporter   ~0014135

So what can I do?
Seems to be something very wrong...
I tried deinnstalling and installing Sogo, but no change...
Is there a way to completly remove everything from the server without reinstalling the whole Server?
Why are no debugging messages???
And: why are the Workers hanging, isn't that an indication of my problem?
Wired...

Christian Mack

Christian Mack

2020-02-05 16:37

developer   ~0014140

Could you check your sope packages?

ballt

ballt

2020-02-05 18:38

reporter   ~0014142

Those are my installed packages:
sope49-appserver.x86_64 4.9-20191218_1900.ns7 @nethforge
sope49-cards.x86_64 4.3.0-1.ns7 @nethforge-testing
sope49-core.x86_64 4.9-20191218_1900.ns7 @nethforge
sope49-gdl1.x86_64 4.9-20191218_1900.ns7 @nethforge
sope49-gdl1-contentstore.x86_64 4.3.0-1.ns7 @nethforge-testing
sope49-gdl1-mysql.x86_64 4.9-20191218_1900.ns7 @nethforge
sope49-ldap.x86_64 4.9-20191218_1900.ns7 @nethforge
sope49-mime.x86_64 4.9-20191218_1900.ns7 @nethforge
sope49-sbjson.x86_64 2.3.1-20191218_1900.ns7
sope49-xml.x86_64 4.9-20191218_1900.ns7 @nethforge
sope49-debuginfo.x86_64 4.9-20191218_1900.ns7 nethforge
sope49-gdl1-contentstore-devel.x86_64 3.2.4-1.ns7 nethforge
sope49-gdl1-postgresql.x86_64 4.9-20191218_1900.ns7 nethforge

How can I check them further?

ballt

ballt

2020-02-16 14:06

reporter   ~0014163

Any help?

ludovic

ludovic

2020-02-16 15:44

administrator   ~0014164

How many sogod workers are you using? How many EAS clients?

It is normal that workers are "hanging" (read sleeping) - that's long-lived HTTP connections for EAS.

ballt

ballt

2020-02-16 17:50

reporter   ~0014165

I only have 2 users. I read that the hanging is normal.
My problem is, Outlook with Active Sync is slow, but works. Website is not working, just gives me an undefined error and Androids can't connect with a useless error message...

ludovic

ludovic

2021-03-10 16:39

administrator   ~0015135

No feedback provided.

Issue History

Date Modified Username Field Change
2020-01-30 08:34 ballt New Issue
2020-01-31 09:09 Christian Mack Note Added: 0014123
2020-02-02 16:17 ballt Note Added: 0014128
2020-02-03 12:46 Christian Mack Note Added: 0014129
2020-02-03 13:52 ballt Note Added: 0014130
2020-02-03 15:48 Christian Mack Note Added: 0014131
2020-02-03 16:45 ballt Note Added: 0014133
2020-02-04 09:05 Christian Mack Note Added: 0014134
2020-02-04 14:10 ballt Note Added: 0014135
2020-02-05 16:37 Christian Mack Note Added: 0014140
2020-02-05 18:38 ballt Note Added: 0014142
2020-02-16 14:06 ballt Note Added: 0014163
2020-02-16 15:44 ludovic Note Added: 0014164
2020-02-16 17:50 ballt Note Added: 0014165
2021-03-10 16:39 ludovic Assigned To => ludovic
2021-03-10 16:39 ludovic Status new => closed
2021-03-10 16:39 ludovic Resolution open => suspended
2021-03-10 16:39 ludovic Note Added: 0015135