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