Jul 22 00:59:22 sogod [77926]: version 5.8.4 (build root@web-01 202307212357) -- starting Jul 22 00:59:22 sogod [77926]: vmem size check enabled: shutting down app when vmem > 1000 MB. Currently at 432 MB Jul 22 00:59:22 sogod [77926]: <0x0x563ecb41efa0[SOGoProductLoader]> SOGo products loaded from '/usr/local/lib64/GNUstep/SOGo': Jul 22 00:59:22 sogod [77926]: <0x0x563ecb41efa0[SOGoProductLoader]> Appointments.SOGo, Contacts.SOGo, Mailer.SOGo, CommonUI.SOGo, ContactsUI.SOGo, MailPartViewers.SOGo, MailerUI.SOGo, MainUI.SOGo, PreferencesUI.SOGo, SchedulerUI.SOGo, AdministrationUI.SOGo, ActiveSync.SOGo Jul 22 00:59:23 sogod [77926]: All products loaded - current memory usage at 484 MB Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> listening on 0.0.0.0:20000 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> watchdog process pid: 77926 Jul 22 00:59:23 sogod [77926]: <0x0x7f95e8b5cfe0[WOWatchDogChild]> watchdog request timeout set to 6 minutes Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> preparing 20 children Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77927 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77928 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77929 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77930 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77931 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77932 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77933 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77934 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77935 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77936 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77937 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77938 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77939 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77940 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77941 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77942 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77943 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77944 Jul 22 00:59:23 sogod [77931]: <0x0x563ecb6734b0[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77945 Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77946 Jul 22 00:59:23 sogod [77927]: <0x0x563ecb6784d0[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77929]: <0x0x563ecb676690[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77933]: <0x0x563ecb670cf0[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77936]: <0x0x563ecb66a1f0[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77928]: <0x0x563ecb677400[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77932]: <0x0x563ecb671e60[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77941]: <0x0x563ecb2a38d0[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77937]: <0x0x563ecb649d40[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77934]: <0x0x563ecb66e520[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77935]: <0x0x563ecb66bfb0[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77939]: <0x0x563ecb624b20[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77930]: <0x0x563ecb66bfb0[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77945]: <0x0x563ecb612a80[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77938]: <0x0x563ecb649d40[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77944]: <0x0x563ecb612770[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77946]: <0x0x563ecb5f3730[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77942]: <0x0x563ecb61cae0[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77943]: <0x0x563ecb615a50[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:23 sogod [77940]: <0x0x563ecb623ec0[WOHttpAdaptor]> notified the watchdog that we are ready Jul 22 00:59:53 sogod [77940]: <0x0x563ecb285be0[SOGoCache]> Cache cleanup interval set every 300.000000 seconds Jul 22 00:59:53 sogod [77940]: <0x0x563ecb285be0[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jul 22 00:59:53 sogod [77943]: <0x0x563ecb27af90[SOGoCache]> Cache cleanup interval set every 300.000000 seconds Jul 22 00:59:53 sogod [77943]: <0x0x563ecb27af90[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jul 22 00:59:53 sogod [77940]: 89.20.1.36 "POST /SOGo/so/testuser01@desplite.ru/Mail/0/folderINBOX/changes HTTP/1.1" 200 19/66 0.128 - - 2M - 13 Jul 22 00:59:53 sogod [77943]: 89.20.1.36 "POST /SOGo/so/testuser01@desplite.ru/Mail/unseenCount HTTP/1.1" 200 19/31 0.130 - - 2M - 12 Jul 22 00:59:54 sogod [77943]: 89.20.1.36 "POST /SOGo/so/testuser01@desplite.ru/Mail/0/folderINBOX/changes HTTP/1.1" 200 19/144 0.109 - - 408K - 13 Jul 22 01:00:00 sogod [77943]: <0x0x563ecb26ac20[SOGoActiveSyncDispatcher]> EAS - request for device KKN5M03JQ97UR7RDM6IU028FB8: -1 mail%2F40b59d35cefcba64cfc53a003abbbae4 25 5 1 0 0 1 500 Jul 22 01:00:00 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Sync HTTP/1.1" 200 0/104 0.183 - - 1M - 13 Jul 22 01:00:00 sogod [77943]: <0x0x563ecb3febe0[SOGoActiveSyncDispatcher]> EAS - request for device KKN5M03JQ97UR7RDM6IU028FB8: -1 mail%2F41b59d35cefcba64cfc53a003abbbae4 25 5 1 0 0 1 500 Jul 22 01:00:00 sogod [77943]: <0x0x563ecb8e93a0[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jul 22 01:00:00 sogod [77943]: <0x0x563ecb3febe0[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (0) - lastObject: (null) Jul 22 01:00:00 sogod [77943]: <0x0x563ecb3febe0[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (1) - lastObject: 1-3 Jul 22 01:00:00 sogod [77943]: <0x0x563ecb3febe0[SOGoActiveSyncDispatcher]> EAS - found in cache: 0 k = 0 Jul 22 01:00:00 sogod [77943]: <0x0x563ecb3febe0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 1-3 Jul 22 01:00:00 sogod [77943]: <0x0x563ecb3febe0[SOGoActiveSyncDispatcher]> EAS - ADD! Jul 22 01:00:00 sogod [77943]: <0x0x563ecb3febe0[SOGoActiveSyncDispatcher]> EAS - first uid added 1 Jul 22 01:00:00 sogod [77943]: <0x0x563ecb3febe0[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jul 22 01:00:00 sogod [77943]: <0x0x563ecb3febe0[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: Email 1-3 mail%2F41b59d35cefcba64cfc53a003abbbae4 1 1 "Тестовый Юзер2" <testuser02@desplite.ru> Тестовый Юзер2 "Тестовый Юзер1" <testuser01@desplite.ru> Приглашение на мероприятие: "test meeting" "test meeting" 2023-07-21T21:51:42.000Z 1 1 IPM.Schedule.Meeting.Request 0 2023-07-22T07:00:00.000Z 2023-07-21T21:51:42.000Z 2023-07-22T08:00:00.000Z 0 qwerty Тестовый Юзер1 <testuser01@desplite.ru> 0 3 2 TP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAADQsAAAPlYAAMD6a4L8fwAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA== 1 BAAAAIIA4AB0xbcQGoLgCAAAAAAAAAAAAAAAAAAAAAAAAAAAJQAAAHZDYWwtVWlkAQAAADEzMDI1LTY0QkFGRTAwLTEtMTZBQzRFMjA= 1 urn:content-classes:calendarmessage Тестовый Юзер1 <testuser01@desplite.ru> 65001 1 0 Приглашение на мероприятие: "test meeting" Тестовый Юзер1 Ваше участие необходимо для этого события Местонахождение qwerty Начало Суббота, Июль 22, 2023 - 10:00 Europe/Moscow Конец Суббота, Июль 22, 2023 - 11:00 Europe/Moscow 1334 0 PDEzMDI4LTY0YmFmZTAwLTMtMjA1YjMyNDBAMjMxNTk1MjU+ 0 2 Jul 22 01:00:00 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Sync HTTP/1.1" 200 1709/104 0.247 - - 1M - 13 Jul 22 01:00:01 sogod [77943]: <0x0x563ecb987b20[SOGoActiveSyncDispatcher]> EAS - request for device KKN5M03JQ97UR7RDM6IU028FB8: 1-3 mail%2F41b59d35cefcba64cfc53a003abbbae4 0 5 0 0 1 1 Jul 22 01:00:01 sogod [77943]: <0x0x563ecb987b20[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jul 22 01:00:01 sogod [77943]: <0x0x563ecb987b20[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: Email 1-3 mail%2F41b59d35cefcba64cfc53a003abbbae4 1 1 1 "Тестовый Юзер2" <testuser02@desplite.ru> Тестовый Юзер2 "Тестовый Юзер1" <testuser01@desplite.ru> Приглашение на мероприятие: "test meeting" "test meeting" 2023-07-21T21:51:42.000Z 1 1 IPM.Schedule.Meeting.Request 0 2023-07-22T07:00:00.000Z 2023-07-21T21:51:42.000Z 2023-07-22T08:00:00.000Z 0 qwerty Тестовый Юзер1 <testuser01@desplite.ru> 0 3 2 TP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAADA+AAAPlYAAND7a4L8fwAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA== 1 BAAAAIIA4AB0xbcQGoLgCAAAAAAAAAAAAAAAAAAAAAAAAAAAJQAAAHZDYWwtVWlkAQAAADEzMDI1LTY0QkFGRTAwLTEtMTZBQzRFMjA= 1 urn:content-classes:calendarmessage Тестовый Юзер1 <testuser01@desplite.ru> 65001 1 0 Приглашение на мероприятие: "test meeting" Тестовый Юзер1 Ваше участие необходимо для этого события Местонахождение qwerty Начало Суббота, Июль 22, 2023 - 10:00 Europe/Moscow Конец Суббота, Июль 22, 2023 - 11:00 Europe/Moscow 1334 0 PDEzMDI4LTY0YmFmZTAwLTMtMjA1YjMyNDBAMjMxNTk1MjU+ 0 2 Jul 22 01:00:01 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Sync HTTP/1.1" 200 1714/102 0.200 - - 312K - 13 Jul 22 01:00:02 sogod [77943]: <0x0x563ecb987b20[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached HeatbeatInterval (354) Jul 22 01:00:02 sogod [77943]: <0x0x563ecb987b20[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached folders ("vevent%2Fpersonal", "mail%2F40b59d35cefcba64cfc53a003abbbae4", "vcard%2Fpersonal", "mail%2F41b59d35cefcba64cfc53a003abbbae4", "vtodo%2Fpersonal") Jul 22 01:00:02 sogod [77943]: <0x0x563ecb91b400[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jul 22 01:00:02 sogod [77943]: <0x0x563ecb91b400[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jul 22 01:00:02 sogod [77943]: <0x0x563ecb91b400[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jul 22 01:00:02 sogod [77943]: <0x0x563ecb987b20[SOGoActiveSyncDispatcher]> Change detected using Ping, we let the EAS client know to send a Sync. Jul 22 01:00:02 sogod [77943]: <0x0x563ecb987b20[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: 2 mail%2F41b59d35cefcba64cfc53a003abbbae4 Jul 22 01:00:02 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Ping HTTP/1.1" 200 58/0 0.207 - - 0 - 14 Jul 22 01:00:02 sogod [77943]: <0x0x563ecb989520[SOGoActiveSyncDispatcher]> EAS - request for device KKN5M03JQ97UR7RDM6IU028FB8: 1-3 mail%2F41b59d35cefcba64cfc53a003abbbae4 25 5 1 0 0 1 500 Jul 22 01:00:03 sogod [77943]: <0x0x563ecba28780[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jul 22 01:00:03 sogod [77943]: <0x0x563ecb989520[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (1) - lastObject: 1-3 Jul 22 01:00:03 sogod [77943]: <0x0x563ecb989520[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (1) - lastObject: 1-3 Jul 22 01:00:03 sogod [77943]: <0x0x563ecb989520[SOGoActiveSyncDispatcher]> EAS - found in cache: 0 k = 0 Jul 22 01:00:03 sogod [77943]: <0x0x563ecb989520[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 1-3 Jul 22 01:00:03 sogod [77943]: <0x0x563ecb989520[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jul 22 01:00:03 sogod [77943]: <0x0x563ecb989520[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: Email 2-3 mail%2F41b59d35cefcba64cfc53a003abbbae4 1 Jul 22 01:00:03 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Sync HTTP/1.1" 200 74/105 0.201 - - 0 - 14 Jul 22 01:00:05 sogod [77943]: <0x0x563ecb6422c0[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached HeatbeatInterval (354) Jul 22 01:00:05 sogod [77943]: <0x0x563ecb6422c0[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached folders ("vevent%2Fpersonal", "mail%2F40b59d35cefcba64cfc53a003abbbae4", "vcard%2Fpersonal", "mail%2F41b59d35cefcba64cfc53a003abbbae4", "vtodo%2Fpersonal") Jul 22 01:00:05 sogod [77940]: <0x0x563ecb2158a0[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached HeatbeatInterval (354) Jul 22 01:00:05 sogod [77940]: <0x0x563ecb2158a0[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached folders ("mail%2F784fc63af739436486952f003abbbae4", "mail%2Fb8eb4307143a436422962f003abbbae4", "vcard%2Fpersonal") Jul 22 01:00:05 sogod [77943]: <0x0x563ecb6422c0[SOGoActiveSyncDispatcher]> Sleeping 30 seconds while detecting changes for user testuser02@desplite.ru in Ping... Jul 22 01:00:18 sogod [77942]: <0x0x563ecb218610[SOGoCache]> Cache cleanup interval set every 300.000000 seconds Jul 22 01:00:18 sogod [77942]: <0x0x563ecb218610[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jul 22 01:00:18 sogod [77946]: <0x0x563ecb286c00[SOGoCache]> Cache cleanup interval set every 300.000000 seconds Jul 22 01:00:18 sogod [77946]: <0x0x563ecb286c00[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jul 22 01:00:18 sogod [77946]: 89.20.1.36 "POST /SOGo/so/testuser02@desplite.ru/Mail/unseenCount HTTP/1.1" 200 19/31 0.141 - - 3M - 13 Jul 22 01:00:18 sogod [77942]: 89.20.1.36 "POST /SOGo/so/testuser02@desplite.ru/Mail/0/folderINBOX/changes HTTP/1.1" 200 709/66 0.196 - - 5M - 14 Jul 22 01:00:19 sogod [77942]: 89.20.1.36 "POST /SOGo/so/testuser02@desplite.ru/Mail/0/folderINBOX/changes HTTP/1.1" 200 709/144 0.165 - - 80K - 14 Jul 22 01:00:35 sogod [77943]: <0x0x563ecb6422c0[SOGoActiveSyncDispatcher]> Change detected using Ping, we let the EAS client know to send a Sync. Jul 22 01:00:35 sogod [77943]: <0x0x563ecb6422c0[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: 2 mail%2F41b59d35cefcba64cfc53a003abbbae4 Jul 22 01:00:35 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Ping HTTP/1.1" 200 58/0 30.307 - - 0 - 14 Jul 22 01:00:35 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> EAS - request for device KKN5M03JQ97UR7RDM6IU028FB8: 2-3 mail%2F41b59d35cefcba64cfc53a003abbbae4 25 5 1 0 0 1 500 Jul 22 01:00:35 sogod [77943]: <0x0x563ecba7b7a0[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jul 22 01:00:35 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (1) - lastObject: 1-3 Jul 22 01:00:35 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (2) - lastObject: 2-4 Jul 22 01:00:35 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> EAS - found in cache: 0 k = 0 Jul 22 01:00:35 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 1-3 Jul 22 01:00:35 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 2-4 Jul 22 01:00:35 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> EAS - ADD! Jul 22 01:00:35 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> EAS - first uid added 2 Jul 22 01:00:36 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jul 22 01:00:36 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: Email 2-4 mail%2F41b59d35cefcba64cfc53a003abbbae4 1 2 "testuser02@desplite.ru" <testuser02@desplite.ru> testuser02@desplite.ru "testuser01@desplite.ru" <testuser01@desplite.ru> Subject of my email Subject of my email 2023-07-21T21:59:35.000Z 1 0 IPM.Schedule.Meeting.Request 0 2023-07-22T11:00:00.000Z 2023-07-21T21:51:42.000Z 2023-07-22T12:00:00.000Z 0 qwerty 0 3 2 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAIAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAEAAAAAAAAAxP///w== 1 BAAAAIIA4AB0xbcQGoLgCAAAAAAAAAAAAAAAAAAAAAAAAAAAJQAAAHZDYWwtVWlkAQAAADEzMDI1LTY0QkFGRTAwLTEtMTZBQzRFMjE= 1 urn:content-classes:calendarmessage testuser01@desplite.ru 65001 1 0 Я перестал понимать. Пуш есть? 180 calendar.ics mail/INBOX/2/2 1 1236 0 PDIwMjMwNzIxMjE1OTM2Ljg1Q0QyMTM4NzhFMjE3QG1haWwtMDEuZnN0LnNoPg== 0 1 Jul 22 01:00:36 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Sync HTTP/1.1" 200 1096/105 0.242 - - 700K - 14 Jul 22 01:00:36 sogod [77943]: <0x0x563ecb90c460[SOGoActiveSyncDispatcher]> EAS - request for device KKN5M03JQ97UR7RDM6IU028FB8: 2-4 mail%2F41b59d35cefcba64cfc53a003abbbae4 0 5 0 0 1 2 Jul 22 01:00:36 sogod [77943]: <0x0x563ecb90c460[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jul 22 01:00:36 sogod [77943]: <0x0x563ecb90c460[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: Email 2-4 mail%2F41b59d35cefcba64cfc53a003abbbae4 1 2 1 "testuser02@desplite.ru" <testuser02@desplite.ru> testuser02@desplite.ru "testuser01@desplite.ru" <testuser01@desplite.ru> Subject of my email Subject of my email 2023-07-21T21:59:35.000Z 1 0 IPM.Schedule.Meeting.Request 0 2023-07-22T11:00:00.000Z 2023-07-21T21:51:42.000Z 2023-07-22T12:00:00.000Z 0 qwerty 0 3 2 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAIAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAEAAAAAAAAAxP///w== 1 BAAAAIIA4AB0xbcQGoLgCAAAAAAAAAAAAAAAAAAAAAAAAAAAJQAAAHZDYWwtVWlkAQAAADEzMDI1LTY0QkFGRTAwLTEtMTZBQzRFMjE= 1 urn:content-classes:calendarmessage testuser01@desplite.ru 65001 1 0 Я перестал понимать. Пуш есть? 180 calendar.ics mail/INBOX/2/2 1 1236 0 PDIwMjMwNzIxMjE1OTM2Ljg1Q0QyMTM4NzhFMjE3QG1haWwtMDEuZnN0LnNoPg== 0 1 Jul 22 01:00:36 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Sync HTTP/1.1" 200 1101/102 0.185 - - 0 - 14 Jul 22 01:00:37 sogod [77943]: <0x0x563ecb9ba010[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached HeatbeatInterval (354) Jul 22 01:00:37 sogod [77943]: <0x0x563ecb9ba010[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached folders ("vevent%2Fpersonal", "mail%2F40b59d35cefcba64cfc53a003abbbae4", "vcard%2Fpersonal", "mail%2F41b59d35cefcba64cfc53a003abbbae4", "vtodo%2Fpersonal") Jul 22 01:00:38 sogod [77943]: <0x0x563ecb9ba010[SOGoActiveSyncDispatcher]> Change detected using Ping, we let the EAS client know to send a Sync. Jul 22 01:00:38 sogod [77943]: <0x0x563ecb9ba010[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: 2 mail%2F41b59d35cefcba64cfc53a003abbbae4 Jul 22 01:00:38 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Ping HTTP/1.1" 200 58/0 0.205 - - 0 - 14 Jul 22 01:00:38 sogod [77943]: <0x0x563ecb131ac0[SOGoActiveSyncDispatcher]> EAS - request for device KKN5M03JQ97UR7RDM6IU028FB8: 2-4 mail%2F41b59d35cefcba64cfc53a003abbbae4 25 5 1 0 0 1 500 Jul 22 01:00:38 sogod [77943]: <0x0x563ecb3fd7a0[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jul 22 01:00:38 sogod [77943]: <0x0x563ecb131ac0[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (2) - lastObject: 2-4 Jul 22 01:00:38 sogod [77943]: <0x0x563ecb131ac0[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (1) - lastObject: 2-4 Jul 22 01:00:38 sogod [77943]: <0x0x563ecb131ac0[SOGoActiveSyncDispatcher]> EAS - found in cache: 0 k = 0 Jul 22 01:00:38 sogod [77943]: <0x0x563ecb131ac0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 2-4 Jul 22 01:00:38 sogod [77943]: <0x0x563ecb131ac0[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jul 22 01:00:38 sogod [77943]: <0x0x563ecb131ac0[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: Email 3-4 mail%2F41b59d35cefcba64cfc53a003abbbae4 1 Jul 22 01:00:38 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Sync HTTP/1.1" 200 74/105 0.195 - - 0 - 14 Jul 22 01:00:40 sogod [77943]: <0x0x563ecb90c500[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached HeatbeatInterval (354) Jul 22 01:00:40 sogod [77943]: <0x0x563ecb90c500[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached folders ("vevent%2Fpersonal", "mail%2F40b59d35cefcba64cfc53a003abbbae4", "vcard%2Fpersonal", "mail%2F41b59d35cefcba64cfc53a003abbbae4", "vtodo%2Fpersonal") Jul 22 01:00:40 sogod [77943]: <0x0x563ecb90c500[SOGoActiveSyncDispatcher]> Sleeping 30 seconds while detecting changes for user testuser02@desplite.ru in Ping... Jul 22 01:00:41 sogod [77942]: <0x0x563ecb9417f0[SOGoActiveSyncDispatcher]> EAS - request for device KKN5M03JQ97UR7RDM6IU028FB8: Mailbox mail/INBOX/2/2 Jul 22 01:00:41 sogod [77942]: <0x0x563ecb9417f0[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: 1 1 mail/INBOX/2/2 text/calendar QkVHSU46VkNBTEVOREFSClBST0RJRDotLy9JbnZlcnNlIGluYy4vU09HbyA1LjguNC8vRU4KVkVSU0lPTjoyLjAKTUVUSE9EOlJFUVVFU1QKQkVHSU46VlRJTUVaT05FClRaSUQ6RXVyb3BlL01vc2NvdwpMQVNULU1PRElGSUVEOjIwMjMwNTIzVDA5MjE1N1oKWC1MSUMtTE9DQVRJT046RXVyb3BlL01vc2NvdwpCRUdJTjpTVEFOREFSRApUWk5BTUU6TVNLClRaT0ZGU0VURlJPTTorMDMwMApUWk9GRlNFVFRPOiswMzAwCkRUU1RBUlQ6MTk3MDAxMDFUMDAwMDAwCkVORDpTVEFOREFSRApFTkQ6VlRJTUVaT05FCkJFR0lOOlZFVkVOVApVSUQ6MTMwMjUtNjRCQUZFMDAtMS0xNkFDNEUyMQpTVU1NQVJZOnRlc3QgbWVldGluZwpMT0NBVElPTjpxd2VydHkKQ0xBU1M6Q09ORklERU5USUFMCkFUVEVOREVFO1JPTEU9M0RSRVEtUEFSVElDSVBBTlQ7UEFSVFNUQVQ9M0RORUVEUy1BQ1RJT047Q049M0Q9RDA9QTI9RDA9QjU9Cj1EMT04MT1EMT04Mj1EMD1CRT1EMD1CMj1EMT04Qj1EMD1COSA9RDA9QUU9RDA9Qjc9RDA9QjU9RDE9ODAyO1JTVlA9M0QKIFRSVUU6bWFpbHRvOnRlc3R1c2VyMDJAZGVzcGxpdGUucnUKVFJBTlNQOk9QQVFVRQpEVFNUQVJUO1RaSUQ9M0RFdXJvcGUvTW9zY293OjIwMjMwNzIyVDExMDAwMApEVEVORDtUWklEPTNERXVyb3BlL01vc2NvdzoyMDIzMDcyMlQxMjAwMDAKT1JHQU5JWkVSO0NOPTNEPUQwPUEyPUQwPUI1PUQxPTgxPUQxPTgyPUQwPUJFPUQwPUIyPUQxPThCPUQwPUI5ID1EMD1BRT1EMD0KPUI3PUQwPUI1PUQxPTgwMTptYWlsdG86dGVzdHVzZXIwMUBkZXNwbGl0ZS5ydQpDUkVBVEVEOjIwMjMwNzIxVDIxNTE0MloKRFRTVEFNUDoyMDIzMDcyMVQyMTUxNDJaCkxBU1QtTU9ESUZJRUQ6MjAyMzA3MjFUMjE1MTQyWgpFTkQ6VkVWRU5UCkVORDpWQ0FMRU5EQVI= Jul 22 01:00:41 sogod [77942]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=ItemOperations HTTP/1.1" 200 1271/41 0.128 - - 384K - 13 Jul 22 01:00:53 sogod [77942]: 89.20.1.36 "POST /SOGo/so/testuser01@desplite.ru/Mail/0/folderINBOX/changes HTTP/1.1" 200 19/66 0.587 - - 0 - 14 Jul 22 01:00:56 sogod [77942]: 89.20.1.36 "POST /SOGo/so/testuser01@desplite.ru/Mail/unseenCount HTTP/1.1" 200 19/31 2.472 - - 0 - 13 Jul 22 01:00:56 sogod [77946]: 89.20.1.36 "POST /SOGo/so/testuser01@desplite.ru/Mail/0/folderINBOX/changes HTTP/1.1" 200 19/144 1.667 - - 0 - 14 Jul 22 01:01:05 sogod [77926]: [WARN] <0x0x563ecb51a860[WOWatchDogChild]> pid 77940 has been hanging in the same request for 1 minutes Jul 22 01:01:10 sogod [77943]: <0x0x563ecb90c500[SOGoActiveSyncDispatcher]> Sleeping 30 seconds while detecting changes for user testuser02@desplite.ru in Ping...