View Issue Details

Jump to NotesJump to History
IDProjectCategoryView StatusDate SubmittedLast Update
0005976SOGoBackend Address Bookpublic2024-05-27 10:442024-05-28 10:39
ReporterArtim Assigned Tosebastien  
PrioritynormalSeverityminorReproducibilityalways
Status assignedResolutionopen 
PlatformServerOSDebianOS Version12.5
Product Version5.8.0 
Summary0005976: sogod errors when trying to create an address book entry
Description

Currently, for some reason, I can't create address book entries. When saving the entry, it just disappears. The SOGo logs show this:

May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key 'session:7ct8NbY9G9h/lXAA': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key 'user+dn': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key 'user+attributes': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key 'user+attributes': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key 'user@fsmuw.rwth-aachen.de+attributes': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key 'session:7ct8NbY9G9h/lXAA': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key 'user+defaults': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key '/Users/user/Contacts/personal': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
2024-05-27 12:10:48.489 sogod[1663:1663] ERROR: could not open MySQL4 connection to database 'sogo': Access denied for user 'sogo'@'localhost' (using password: YES)
May 27 12:10:48 sogod [1663]: [ERROR] <0x0x561a5d607080[GCSChannelManager]> could not open channel <MySQL4Channel[0x0x561a5fe27a10] connection=0x(null)> for mysql://localhost/sogo/sogouser0034265f6a9
May 27 12:10:48 sogod [1663]: [WARN] <0x0x561a5d607080[GCSChannelManager]> will prevent opening of this channel 5 seconds after 2024-05-27 12:10:48 +0200
May 27 12:10:48 sogod [1663]: [ERROR] <0x0x561a5fce9050[GCSFolder]> could not open storage channel!
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key 'session:7ct8NbY9G9h/lXAA': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: [ERROR] <0x0x561a5fce9050[GCSFolder]> could not open storage channel!
May 27 12:10:48 sogod [1663]: [ERROR] <0x0x561a5fce9050[GCSFolder]> could not open storage channel!
May 27 12:10:48 sogod [1663]: [ERROR] <0x0x561a5fce9050[GCSFolder]> -[GCSFolder writeContent:fromComponent:container:toName:baseVersion:]: could not open storage channel!
May 27 12:10:48 sogod [1663]: [ERROR] <0x0x561a5fce9050[GCSFolder]> could not open storage channel!
May 27 12:10:48 sogod [1663]: 127.0.0.1 "POST /SOGo/so/user/Contacts/personal/680-66545C00-23-2AB64000.vcf/saveAsContact HTTP/1.1" 200 54/289 0.043 - - 324K - 14
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key 'session:7ct8NbY9G9h/lXAA': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key 'user+dn': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key 'user+attributes': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key 'user+attributes': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key 'user@fsmuw.rwth-aachen.de+attributes': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key 'session:7ct8NbY9G9h/lXAA': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key 'user+defaults': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key 'session:7ct8NbY9G9h/lXAA': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key 'user+settings': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key 'user+settings': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: <0x0x561a5d860f60[SOGoCache]> an error occurred when caching value for key '/Users/user/Contacts/personal': "SERVER HAS FAILED AND IS DISABLED UNTIL TIMED RETRY"
May 27 12:10:48 sogod [1663]: [ERROR] <0x0x561a6039da80[GCSFolder]> could not open storage channel!
May 27 12:10:48 sogod [1663]: 127.0.0.1 "POST /SOGo/so/user/Contacts/personal/view HTTP/1.1" 200 209/46 0.026 - - 0 - 14

The sogo config has these lines:
SOGoProfileURL = "postgresql://sogo:password@127.0.0.1:5432/sogo/sogo_user_profile";
OCSFolderInfoURL = "postgresql://sogo:password@127.0.0.1:5432/sogo/sogo_folder_info";
OCSSessionsFolderURL = "postgresql://sogo:password@127.0.0.1:5432/sogo/sogo_sessions_folder";
OCSEMailAlarmsFolderURL = "postgresql://sogo:password@127.0.0.1:5432/sogo/sogo_alarms_folder";

MySQL4DebugEnabled is set to YES

The issue already existed before migrating from mysql/MariaDB to Postgres. Also, what very much irritates me is the error that sogo supposedly can't connect to the database. Doing it manually with sudo -u sogo psql --username=sogo and then entering the password isn't an issue. The user sogo is listed as the owner of the database and every table.

Steps To Reproduce
  1. go do address book
  2. create new entry
  3. select save
  4. see entry disappear.
TagsNo tags attached.

Activities

sebastien

sebastien

2024-05-28 08:22

administrator   ~0017740

Hi,

The error means SOGo can not connect to the database.

  • Do you reproduce on demo.sogo.nu ?
  • Check your connection between db / SOGo
  • Can you reproduce with latest nightly ?

Sebastien
Artim

Artim

2024-05-28 10:39

reporter   ~0017743

It's not reproducible on the demo server. As said, I can't see any issues between the db and SOGo. Both are on the same server and there's absolutely no reason SOGo shouldn't be able to talk to the database. Using the username and password given in the config and acting as the local user "sogo" - for all I can tell that user should be used by sogo itself too - doesn't have any issues.

What confuses me is that the "Access denied for user 'sogo'@'localhost'" logs seem to be stemming from mariadb. At least that's what the systemd journal says. But the config clearly states that postgres is to be used. So everything indicates that the issue is SOGo itself not talking to the right database backend.

And no, I haven't tested with the latest nightly. As SOGo likes to change database schemas, I'm not comfortable upgrading beyond what Debian has in their repos. I've had too bad of an experience with that the past years. The only way I know that should allow having both versions in parallel would be compiling from source, yet neither SOGo nor sope compile, both fail with fatal errors at the beginning of make.

Issue History

Date Modified Username Field Change
2024-05-27 10:44 Artim New Issue
2024-05-28 08:22 sebastien Note Added: 0017740
2024-05-28 08:22 sebastien Assigned To => sebastien
2024-05-28 08:22 sebastien Status new => feedback
2024-05-28 10:39 Artim Note Added: 0017743
2024-05-28 10:39 Artim Status feedback => assigned