HI,
I’m still puzzled what is causing this.
I don’t see any alarming logging, below latest logging when the server had spykes above 50% CPU.
What can I do to make visible what is causing this?
05/10/2021, 2:34:35 PM notice [111374]: 172.22.1.1 “POST /SOGo/Microsoft-Server-ActiveSync?User=user1@mydomain.nl&DeviceId=8BN7DER7AH7JB33KJFK46BU1DG&DeviceType=iPad&Cmd=Sync HTTP/1.0” 200 336/114 140.490 - - 4070M - 12
05/10/2021, 2:34:28 PM notice [111374]: <0×0×5611223a2220[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
05/10/2021, 2:34:23 PM notice [111187]: <0×0×5611235d3930[SOGoActiveSyncDispatcher]> Sleeping 45 seconds while detecting changes for user user2@mydomain.nl in Ping…
05/10/2021, 2:34:20 PM notice [111187]: 192.168.0.132 “POST /SOGo/Microsoft-Server-ActiveSync?User=user2@mydomain.nl&DeviceId=URO7LITDGT1191UHKGBUGSDKV8&DeviceType=iPhone&Cmd=Sync HTTP/1.0” 200 5030/114 1.816 - - 3M - 12
05/10/2021, 2:34:20 PM notice [111187]: <0×0×5611235cceb0[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
05/10/2021, 2:34:20 PM notice [113493]: <0×0×561121c26980[WOHttpAdaptor]> notified the watchdog that we are ready
05/10/2021, 2:34:20 PM notice [11]: <0×0×561121b74e40[WOWatchDog]> child spawned with pid 113493
05/10/2021, 2:34:20 PM notice [11]: <0×0×561121c52c70[WOWatchDogChild]> child 82936 exited
05/10/2021, 2:34:19 PM notice [82936]: 192.168.0.225 “POST /SOGo/so/user2@mydomain.nl/Mail/0/folderINBOX/view HTTP/1.0” 200 506425/58 7.712 - - 34M - 14
05/10/2021, 2:34:19 PM notice [111187]: <0×0×561123976d90[NGImap4Client]> Note: no key found for sorting, using ‘DATE’: (null)
05/10/2021, 2:34:19 PM notice [51]: 192.168.0.225 “POST /SOGo/so/user2@mydomain.nl/Mail/0/folderINBOX/view HTTP/1.0” 200 506425/58 12.770 - - 38M - 13
05/10/2021, 2:34:18 PM notice [111187]: 192.168.0.132 “POST /SOGo/Microsoft-Server-ActiveSync?User=user2@mydomain.nl&DeviceId=URO7LITDGT1191UHKGBUGSDKV8&DeviceType=iPhone&Cmd=Ping HTTP/1.0” 200 58/0 11.924 - - 13M - 12
05/10/2021, 2:34:18 PM notice [111187]: <0×0×561121a44b10[SOGoActiveSyncDispatcher]> Change detected using Ping, we let the EAS client know to send a Sync.
05/10/2021, 2:34:17 PM notice [113418]: 192.168.0.225 “POST /SOGo/so/user2@mydomain.nl/Mail/0/folderINBOX/view HTTP/1.0” 200 814/126 73.401 - - 12M - 12
05/10/2021, 2:34:17 PM notice [111326]: mailcowdockerized_watchdog-mailcow_1.mailcowdockerized_mailcow-network “GET /SOGo.index/ HTTP/1.1” 200 2531/0 2.113 - - 17M - 12
05/10/2021, 2:34:16 PM notice [113476]: <0×0×561121dc0560[WOHttpAdaptor]> notified the watchdog that we are ready
05/10/2021, 2:34:15 PM notice [11]: [WARN] <0×0×561121cf68b0[WOWatchDogChild]> pid 111374 has been hanging in the same request for 2 minutes
05/10/2021, 2:34:15 PM notice [113418]: <0×0×561121afcfa0[SOGoCache]> Using host(s) ‘memcached’ as server(s)
05/10/2021, 2:34:15 PM notice [113418]: <0×0×561121afcfa0[SOGoCache]> Cache cleanup interval set every 900.000000 seconds
05/10/2021, 2:34:14 PM notice [11]: <0×0×561121b74e40[WOWatchDog]> child spawned with pid 113476
05/10/2021, 2:34:14 PM notice [11]: <0×0×561121c529c0[WOWatchDogChild]> (terminated due to signal 9)
05/10/2021, 2:34:14 PM notice [11]: <0×0×561121c529c0[WOWatchDogChild]> child 112887 exited
05/10/2021, 2:34:13 PM notice [81958]: 192.168.0.225 “POST /SOGo/so/user2@mydomain.nl/Mail/unseenCount HTTP/1.0” 200 21/31 6.969 - - 9M - 12
05/10/2021, 2:34:13 PM notice [112882]: 192.168.0.225 “POST /SOGo/so/user2@mydomain.nl/Mail/unseenCount HTTP/1.0” 200 21/31 75.951 - - 3M - 13
05/10/2021, 2:34:11 PM notice [108240]: <0×0×561122cebda0[SOGoActiveSyncDispatcher]> Sleeping 45 seconds while detecting changes for user user2@mydomain.nl in Ping…
05/10/2021, 2:34:11 PM notice 14:34:11.378 sogod[82936:82936] Forcing termination of EAS loop.
05/10/2021, 2:34:06 PM notice [11]: [WARN] <0×0×561121c51540[WOWatchDogChild]> pid 113418 has been hanging in the same request for 1 minutes
05/10/2021, 2:34:06 PM notice [11]: [WARN] <0×0×561121c50400[WOWatchDogChild]> pid 112882 has been hanging in the same request for 1 minutes
05/10/2021, 2:34:06 PM notice [11]: [WARN] <0×0×561121c51730[WOWatchDogChild]> pid 108240 has been hanging in the same request for 2 minutes
05/10/2021, 2:34:06 PM notice [11]: <0×0×561121c52c70[WOWatchDogChild]> sending terminate signal to pid 82936
05/10/2021, 2:34:06 PM notice [11]: [ERROR] <0×0×561121c52c70[WOWatchDogChild]> FAILURE notifying child 82936
05/10/2021, 2:34:06 PM notice [11]: <0×0×561121c52c70[WOWatchDogChild]> sending terminate signal to pid 82936
05/10/2021, 2:34:06 PM notice [11]: [ERROR] <0×0×561121c52c70[WOWatchDogChild]> exception: <NGSocketTimedOutException: 0×561121dc0810> NAME:NGSocketTimedOutException REASON:the socket was shutdown INFO:{errno = 11; error = “Resource temporarily unavailable”; stream = “{object = 0×561121c61300;}”; }
05/10/2021, 2:34:06 PM notice [11]: [ERROR] <0×0×561121c52c70[WOWatchDogChild]> socket: <NGActiveSocket[0×0×561121c61300]: mode=rw address=(null) connectedTo=<0×0×561121d91940[NGLocalSocketAddress]: /tmp/_ngsocket_11_0×561121916c70_000> receive-timeout=5.000s>
05/10/2021, 2:34:06 PM notice [11]: [ERROR] <0×0×561121c52c70[WOWatchDogChild]> FAILURE receiving status for child 82936
05/10/2021, 2:33:56 PM notice [11]: [WARN] <0×0×561121c529c0[WOWatchDogChild]> pid 112887 has been hanging in the same request for 1 minutes
05/10/2021, 2:33:39 PM notice [11]: [WARN] <0×0×561121cf68b0[WOWatchDogChild]> pid 111374 has been hanging in the same request for 1 minutes
05/10/2021, 2:32:51 PM notice [11]: [WARN] <0×0×561121c51730[WOWatchDogChild]> pid 108240 has been hanging in the same request for 1 minutes
05/10/2021, 2:32:47 PM notice [108240]: <0×0×561122cebda0[SOGoActiveSyncDispatcher]> Sleeping 45 seconds while detecting changes for user user2@mydomain.nl in Ping…
05/10/2021, 2:32:32 PM notice [112882]: mailcowdockerized_watchdog-mailcow_1.mailcowdockerized_mailcow-network “GET /SOGo.index/ HTTP/1.1” 200 2531/0 0.317 - - -13344K - 13
05/10/2021, 2:32:24 PM notice [112887]: <0×0×5611235de880[SOGoActiveSyncDispatcher]> Sleeping 45 seconds while detecting changes for user user1@mydomain.nl in Ping…
05/10/2021, 2:32:23 PM notice [112887]: [WARN] <0×0×5611218ba850[SOGoWebDAVAclManager]> entry ‘{DAV:}write-content’ already exists in DAV permissions table
05/10/2021, 2:32:23 PM notice [112887]: [WARN] <0×0×5611218ba850[SOGoWebDAVAclManager]> entry ‘{DAV:}write-properties’ already exists in DAV permissions table
05/10/2021, 2:32:23 PM notice [112887]: [WARN] <0×0×5611218ba850[SOGoWebDAVAclManager]> entry ‘{DAV:}write’ already exists in DAV permissions table
05/10/2021, 2:32:23 PM notice [111374]: <0×0×561121c9aff0[NGImap4Client]> Note: no key found for sorting, using ‘DATE’: (null)
05/10/2021, 2:32:14 PM notice [111374]: 89.205.135.241 “POST /SOGo/Microsoft-Server-ActiveSync?User=user1@mydomain.nl&DeviceId=B69JGO3VRL06J012UNIG4PN4QK&DeviceType=iPhone&Cmd=Ping HTTP/1.0” 200 13/191 912.755 - - 1M - 12
05/10/2021, 2:32:12 PM notice [112887]: 192.168.0.238 “POST /SOGo/Microsoft-Server-ActiveSync?User=user1@mydomain.nl&DeviceId=8BN7DER7AH7JB33KJFK46BU1DG&DeviceType=iPad&Cmd=Sync HTTP/1.0” 200 336/114 77.638 - - 4048M - 12
05/10/2021, 2:32:05 PM notice [112887]: <0×0×561121d45c90[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
05/10/2021, 2:32:01 PM notice [11]: [WARN] <0×0×561121cf68b0[WOWatchDogChild]> pid 111374 has been hanging in the same request for 15 minutes
05/10/2021, 2:31:58 PM notice [112882]: 192.168.0.225 “POST /SOGo/so/user2@mydomain.nl/Mail/0/folderINBOX/view HTTP/1.0” 200 506425/58 3.276 - - 18M -