View Issue Details

IDProjectCategoryView StatusLast Update
0005601SOGowith SOGopublic2022-09-26 07:02
Reporterbriandlees Assigned Tosmizrahi  
PriorityhighSeverityblockReproducibilityalways
Status assignedResolutionopen 
Product Version5.7.1 
Summary0005601: Cannot start service due to segmentation fault
Description

I have installed SOGo5 and when trying to start the service I get this error:

Sep 13 15:48:14 acl001 kernel: sogod[21187]: segfault at 100000040 ip 00007f546f3b57a5 sp 00007fff146b3088 error 4 in libobjc.so.4.0.0[7f546f3a8000+14000]
Sep 13 15:48:14 acl001 systemd[1]: sogod.service: Control process exited, code=dumped, status=11/SEGV

I'm sure it's an installation issue and not a bug in the SOGo product but can you point me in the direction of how to figure out what the issue is?

Steps To Reproduce

This does not happen on all servers.

TagsNo tags attached.

Activities

smizrahi

smizrahi

2022-09-14 07:12

administrator   ~0016222

Hi briandlees,

Could you please provide the backtrace ? https://www.sogo.nu/support/faq/how-do-i-debug-sogo.html

Thank you

briandlees

briandlees

2022-09-19 17:02

reporter   ~0016231

I'm using openSUSE 15.3 and none of the debug tools seem to be available as part of the distribution.

briandlees

briandlees

2022-09-19 17:21

reporter   ~0016232

Some additional information. I was previously using SOGo3 and it worked fine. I then upgraded from openSUSE 15.2 to 15.3 which also changed SOGo3 to SOGo5. It never worked again! I uninstalled and started again and got the faults described.

I have also installed SOGo5 on a second server with the same issues as described for the first. A third server was more successful and SOGo5 runs but fails with another segmentation fault when I try to log into it. The relevant sogo.log entries are attached, with some information obfuscated.

220918_1815_sogo_log (11,737 bytes)   
Sep 19 18:06:18 sogod [23499]: version 5.3.0 (build abuild@build83 202111261341) -- starting
Sep 19 18:06:18 sogod [23499]: vmem size check enabled: shutting down app when vmem > 384 MB. Currently at 193 MB
Sep 19 18:06:18 sogod [23499]: <0x0x56281f204c00[SOGoProductLoader]> SOGo products loaded from '/usr/lib64/GNUstep/SOGo':
Sep 19 18:06:18 sogod [23499]: <0x0x56281f204c00[SOGoProductLoader]>   ActiveSync.SOGo, AdministrationUI.SOGo, CommonUI.SOGo, MailPartViewers.SOGo, Contacts.SOGo, MailerUI.SOGo, MainUI.SOGo, PreferencesUI.SOGo, ContactsUI.SOGo, Mailer.SOGo, Appointments.SOGo, SchedulerUI.SOGo
Sep 19 18:06:18 sogod [23499]: All products loaded - current memory usage at 254 MB
Sep 19 18:06:18 sogod [23499]: <0x0x56281f2333f0[WOWatchDog]> listening on 192.168.0.191:20000
Sep 19 18:06:18 sogod [23499]: <0x0x56281f2333f0[WOWatchDog]> watchdog process pid: 23499
Sep 19 18:06:18 sogod [23499]: <0x0x7f262cc78360[WOWatchDogChild]> watchdog request timeout set to 10 minutes
Sep 19 18:06:18 sogod [23499]: <0x0x56281f2333f0[WOWatchDog]> preparing 3 children
Sep 19 18:06:18 sogod [23499]: <0x0x56281f2333f0[WOWatchDog]> child spawned with pid 23503
Sep 19 18:06:18 sogod [23499]: <0x0x56281f2333f0[WOWatchDog]> child spawned with pid 23504
Sep 19 18:06:18 sogod [23499]: <0x0x56281f2333f0[WOWatchDog]> child spawned with pid 23505
2022-09-19 18:06:18.386 sogod[23505:23505] PostgreSQL72 connection established: <0x0x56281f41b230[PGConnection]:  connection=0x0x56281f360950>
2022-09-19 18:06:18.386 sogod[23505:23505] PostgreSQL72 channel 0x0x56281f2ae0d0 opened (connection=<0x0x56281f41b230[PGConnection]:  connection=0x0x56281f360950>, count=1)
2022-09-19 18:06:18.387 sogod[23505:23505] PG0x0x56281f2ae0d0 SQL: SELECT count(*) FROM sogo_user_profile
2022-09-19 18:06:18.388 sogod[23505:23505] PG0x0x56281f2ae0d0 SQL: SELECT count(*) FROM sogo_folder_info
2022-09-19 18:06:18.391 sogod[23505:23505] PG0x0x56281f2ae0d0 SQL: SELECT count(*) FROM sogo_sessions_folder
Sep 19 18:06:18 sogod [23505]: <0x0x56281f03bd60[WOHttpAdaptor]> notified the watchdog that we are ready
2022-09-19 18:06:18.396 sogod[23503:23503] PostgreSQL72 connection established: <0x0x56281f3629d0[PGConnection]:  connection=0x0x56281f5e3970>
2022-09-19 18:06:18.396 sogod[23503:23503] PostgreSQL72 channel 0x0x56281f2ae0d0 opened (connection=<0x0x56281f3629d0[PGConnection]:  connection=0x0x56281f5e3970>, count=1)
2022-09-19 18:06:18.396 sogod[23503:23503] PG0x0x56281f2ae0d0 SQL: SELECT count(*) FROM sogo_user_profile
2022-09-19 18:06:18.398 sogod[23504:23504] PostgreSQL72 connection established: <0x0x56281f3dfe70[PGConnection]:  connection=0x0x56281f642510>
2022-09-19 18:06:18.398 sogod[23504:23504] PostgreSQL72 channel 0x0x56281f2ae0d0 opened (connection=<0x0x56281f3dfe70[PGConnection]:  connection=0x0x56281f642510>, count=1)
2022-09-19 18:06:18.398 sogod[23504:23504] PG0x0x56281f2ae0d0 SQL: SELECT count(*) FROM sogo_user_profile
2022-09-19 18:06:18.398 sogod[23503:23503] PG0x0x56281f2ae0d0 SQL: SELECT count(*) FROM sogo_folder_info
2022-09-19 18:06:18.399 sogod[23504:23504] PG0x0x56281f2ae0d0 SQL: SELECT count(*) FROM sogo_folder_info
2022-09-19 18:06:18.400 sogod[23503:23503] PG0x0x56281f2ae0d0 SQL: SELECT count(*) FROM sogo_sessions_folder
2022-09-19 18:06:18.400 sogod[23504:23504] PG0x0x56281f2ae0d0 SQL: SELECT count(*) FROM sogo_sessions_folder
Sep 19 18:06:18 sogod [23503]: <0x0x56281f0a1310[WOHttpAdaptor]> notified the watchdog that we are ready
Sep 19 18:06:18 sogod [23504]: <0x0x56281f07a500[WOHttpAdaptor]> notified the watchdog that we are ready
Sep 19 18:07:07 sogod [23504]: |SOGo| starting method 'GET' on uri '/SOGo'
Sep 19 18:07:07 sogod [23504]: <0x0x56281f1d1010[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Sep 19 18:07:07 sogod [23504]: <0x0x56281f1d1010[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Sep 19 18:07:07 sogod [23504]: [WARN] <0x0x7f262cc99f60[WOxElemBuilder]> could not locate builders: WOxExtElemBuilder,WOxExtElemBuilder
2022-09-19 18:07:07.251 sogod[23504:23504] PG0x0x56281f2ae0d0 SQL: SELECT c_defaults FROM sogo_user_profile WHERE c_uid = 'anonymous'
Sep 19 18:07:07 sogod [23504]: |SOGo| request took 0.022501 seconds to execute
Sep 19 18:07:07 sogod [23504]: <xx.xx.xx.xx> "GET /SOGo HTTP/1.1" 302 0/0 0.025 - - 3M - 12
Sep 19 18:07:07 sogod [23504]: |SOGo| starting method 'GET' on uri '/SOGo/'
Sep 19 18:07:07 sogod [23504]: |SOGo|   constructed root-url: /SOGo/
Sep 19 18:07:07 sogod [23504]: |SOGo|   setting root-url in context: /SOGo/
Sep 19 18:07:07 sogod [23504]: |SOGo| ROOT baseURL(no container, name=(null)):
  own: /SOGo/
Sep 19 18:07:07 sogod [23504]: |SOGo| request took 0.017833 seconds to execute
Sep 19 18:07:07 sogod [23504]: <xx.xx.xx.xx> "GET /SOGo/ HTTP/1.1" 200 32247/0 0.018 - - 1M - 12
Sep 19 18:07:10 sogod [23504]: |SOGo| starting method 'POST' on uri '/SOGo/connect'
Sep 19 18:07:10 sogod [23504]: <0x0x56281f5543c0[NGLdapConnection]> Using ldap_initialize for LDAP URL: ldap://ldap.xx.ltd.uk
Sep 19 18:07:10 sogod [23504]: <0x0x56281f35e060[NGLdapConnection]> Using ldap_initialize for LDAP URL: ldap://ldap.xx.ltd.uk
2022-09-19 18:07:10.140 sogod[23504:23504] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search at base '' filter '(objectClass=*)' for attrs 'subschemaSubentry'
2022-09-19 18:07:10.141 sogod[23504:23504] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search at base 'cn=schema' filter '(objectClass=*)' for attrs 'objectclasses'
2022-09-19 18:07:10.183 sogod[23504:23504] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search at base 'ou=people,dc=aproposconsulting,dc=ltd,dc=uk' filter '(|(uid=brian)(mail=brian))' for attrs 'dn'
Sep 19 18:07:10 sogod [23504]: SOGoRootPage successful login from '88.107.207.167' for user 'brian' - expire = -1  grace = -1
Sep 19 18:07:10 sogod [23504]: <0x0x56281f8de4d0[NGLdapConnection]> Using ldap_initialize for LDAP URL: ldap://ldap.xx.ltd.uk
2022-09-19 18:07:10.188 sogod[23504:23504] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search at base 'ou=people,dc=xx,dc=ltd,dc=uk' filter '(|(uid=brian)(mail=brian))' for attrs '*'
2022-09-19 18:07:10.191 sogod[23504:23504] PG0x0x56281f2ae0d0 SQL: SELECT c_defaults FROM sogo_user_profile WHERE c_uid = 'brian'
2022-09-19 18:07:10.193 sogod[23504:23504] PG0x0x56281f2ae0d0 SQL: SELECT c_settings FROM sogo_user_profile WHERE c_uid = 'brian'
2022-09-19 18:07:10.199 sogod[23504:23504] PostgreSQL72 connection established: <0x0x56281f90efd0[PGConnection]:  connection=0x0x56281f90f1b0>
2022-09-19 18:07:10.200 sogod[23504:23504] PostgreSQL72 channel 0x0x56281f8f0170 opened (connection=<0x0x56281f90efd0[PGConnection]:  connection=0x0x56281f90f1b0>, count=2)
2022-09-19 18:07:10.200 sogod[23504:23504] PG0x0x56281f8f0170 SQL: BEGIN TRANSACTION
2022-09-19 18:07:10.200 sogod[23504:23504] PG0x0x56281f8f0170 SQL: SELECT t1.c_creationdate, t1.c_id, t1.c_lastseen, t1.c_value FROM  sogo_sessions_folder t1 WHERE t1.c_id='zLEa7+IYfLnbdBPHvRAUbQ=='
2022-09-19 18:07:10.203 sogod[23504:23504] PG0x0x56281f8f0170 SQL: ROLLBACK TRANSACTION
2022-09-19 18:07:10.203 sogod[23504:23504] PG0x0x56281f2ae0d0 SQL: BEGIN TRANSACTION
2022-09-19 18:07:10.203 sogod[23504:23504] PG0x0x56281f2ae0d0 SQL: INSERT INTO sogo_sessions_folder (c_lastseen, c_creationdate, c_value, c_id) VALUES (1663607230, 1663607230, 'iZOtRXqvGCe1cOFK3SQ2FE/SzcUpL874stenz8kGGzRXi4p6TFiJTGU4+YL0beUU4lddvU8pC3lpMAC9mX/4vIBuSsB9Fxvd+RJHXU6Qp52PuNmF4rnzTiw9StAX+83SaJBheIzN1Vjv/+hCaryUqdYV4gp67T2xVT4EBwhDg51p3qUykpZTREE+ZASO5sUJTqp1k4vW47DcuEwAd5fTOA==', 'zLEa7+IYfLnbdBPHvRAUbQ==')
2022-09-19 18:07:10.204 sogod[23504:23504] PG0x0x56281f2ae0d0 SQL: COMMIT TRANSACTION
Sep 19 18:07:10 sogod [23499]: <0x0x56281f3179c0[WOWatchDogChild]> child 23504 exited
Sep 19 18:07:10 sogod [23499]: <0x0x56281f3179c0[WOWatchDogChild]>  (terminated due to signal 11, coredump)
Sep 19 18:07:10 sogod [23499]: <0x0x56281f2333f0[WOWatchDog]> child spawned with pid 23972
Sep 19 18:07:10 sogod [23503]: |SOGo| starting method 'POST' on uri '/SOGo/connect'
Sep 19 18:07:10 sogod [23503]: <0x0x56281f0c0fd0[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Sep 19 18:07:10 sogod [23503]: <0x0x56281f0c0fd0[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Sep 19 18:07:10 sogod [23503]: [WARN] <0x0x7f262cc99f60[WOxElemBuilder]> could not locate builders: WOxExtElemBuilder,WOxExtElemBuilder
Sep 19 18:07:10 sogod [23503]: <0x0x56281f3a0700[NGLdapConnection]> Using ldap_initialize for LDAP URL: ldap://ldap.aproposconsulting.ltd.uk
Sep 19 18:07:10 sogod [23503]: SOGoRootPage successful login from 'xx.xx.xx.xx' for user 'brian' - expire = -1  grace = -1
2022-09-19 18:07:10.275 sogod[23503:23503] PostgreSQL72 connection established: <0x0x56281f6d9230[PGConnection]:  connection=0x0x56281f0a1840>
2022-09-19 18:07:10.275 sogod[23503:23503] PostgreSQL72 channel 0x0x56281f73d180 opened (connection=<0x0x56281f6d9230[PGConnection]:  connection=0x0x56281f0a1840>, count=2)
2022-09-19 18:07:10.275 sogod[23503:23503] PG0x0x56281f73d180 SQL: BEGIN TRANSACTION
2022-09-19 18:07:10.275 sogod[23503:23503] PG0x0x56281f73d180 SQL: SELECT t1.c_creationdate, t1.c_id, t1.c_lastseen, t1.c_value FROM  sogo_sessions_folder t1 WHERE t1.c_id='i6njERXASnwcsDNXVV9PiA=='
2022-09-19 18:07:10.278 sogod[23503:23503] PG0x0x56281f73d180 SQL: ROLLBACK TRANSACTION
2022-09-19 18:07:10.279 sogod[23503:23503] PG0x0x56281f2ae0d0 SQL: BEGIN TRANSACTION
2022-09-19 18:07:10.279 sogod[23503:23503] PG0x0x56281f2ae0d0 SQL: INSERT INTO sogo_sessions_folder (c_lastseen, c_creationdate, c_value, c_id) VALUES (1663607230, 1663607230, 'A+uOqDhlq0QqIIXbAMMpZLtzjBrCpIBje4MvRlJ/p3VkXTNo9b8JSvPMVhh3jXKWweptAEL6VAReFGMSBHAn/zt6OEA/iWwZpDS3uGefykv4TVlDwLhsuIFqYrlb0pr31Pfb2BsM7CJ954DLSwmTSt754kdi0mrlljfhi0AY9IQUFR57bdEjyYm4jsw693yw35l1kS52zsSvH9nFNlifTg==', 'i6njERXASnwcsDNXVV9PiA==')
2022-09-19 18:07:10.280 sogod[23503:23503] PG0x0x56281f2ae0d0 SQL: COMMIT TRANSACTION
Sep 19 18:07:10 sogod [23499]: <0x0x56281f04a930[WOWatchDogChild]> child 23503 exited
Sep 19 18:07:10 sogod [23499]: <0x0x56281f04a930[WOWatchDogChild]>  (terminated due to signal 11, coredump)
Sep 19 18:07:10 sogod [23499]: <0x0x56281f2333f0[WOWatchDog]> child spawned with pid 23974
2022-09-19 18:07:10.322 sogod[23972:23972] PostgreSQL72 connection established: <0x0x56281f5f60a0[PGConnection]:  connection=0x0x56281f5ec530>
2022-09-19 18:07:10.322 sogod[23972:23972] PostgreSQL72 channel 0x0x56281f2ae0d0 opened (connection=<0x0x56281f5f60a0[PGConnection]:  connection=0x0x56281f5ec530>, count=1)
2022-09-19 18:07:10.322 sogod[23972:23972] PG0x0x56281f2ae0d0 SQL: SELECT count(*) FROM sogo_user_profile
2022-09-19 18:07:10.324 sogod[23972:23972] PG0x0x56281f2ae0d0 SQL: SELECT count(*) FROM sogo_folder_info
2022-09-19 18:07:10.325 sogod[23972:23972] PG0x0x56281f2ae0d0 SQL: SELECT count(*) FROM sogo_sessions_folder
Sep 19 18:07:10 sogod [23972]: <0x0x56281f0688d0[WOHttpAdaptor]> notified the watchdog that we are ready
2022-09-19 18:07:10.385 sogod[23974:23974] PostgreSQL72 connection established: <0x0x56281f5f60a0[PGConnection]:  connection=0x0x56281f5ec530>
2022-09-19 18:07:10.385 sogod[23974:23974] PostgreSQL72 channel 0x0x56281f2ae0d0 opened (connection=<0x0x56281f5f60a0[PGConnection]:  connection=0x0x56281f5ec530>, count=1)
2022-09-19 18:07:10.385 sogod[23974:23974] PG0x0x56281f2ae0d0 SQL: SELECT count(*) FROM sogo_user_profile
2022-09-19 18:07:10.387 sogod[23974:23974] PG0x0x56281f2ae0d0 SQL: SELECT count(*) FROM sogo_folder_info
2022-09-19 18:07:10.390 sogod[23974:23974] PG0x0x56281f2ae0d0 SQL: SELECT count(*) FROM sogo_sessions_folder
Sep 19 18:07:10 sogod [23974]: <0x0x56281f0688d0[WOHttpAdaptor]> notified the watchdog that we are ready
220918_1815_sogo_log (11,737 bytes)   
smizrahi

smizrahi

2022-09-26 07:02

administrator   ~0016243

The second server was clean installed with default configuration settings ?

Without debugging symbols / backtrace we can't get the reason of the segfault.

Issue History

Date Modified Username Field Change
2022-09-13 14:57 briandlees New Issue
2022-09-14 07:12 smizrahi Note Added: 0016222
2022-09-14 07:12 smizrahi Assigned To => smizrahi
2022-09-14 07:12 smizrahi Status new => feedback
2022-09-19 17:02 briandlees Note Added: 0016231
2022-09-19 17:02 briandlees Status feedback => assigned
2022-09-19 17:21 briandlees Note Added: 0016232
2022-09-19 17:21 briandlees File Added: 220918_1815_sogo_log
2022-09-26 07:02 smizrahi Note Added: 0016243