Hi,
Just for our understanding:
We are getting regular sogod logs lines like:
Sep 08 06:31:33 sogod [2781]: [WARN] <0x0x7f10a07acc30[WOWatchDogChild]> pid
6087 has been hanging in the same request for 7 minutes
Sep 08 06:31:39 sogod [2781]: [WARN] <0x0x7f10a080b6e0[WOWatchDogChild]> pid
2819 has been hanging in the same request for 35 minutes
Sep 08 06:31:43 sogod [2781]: [WARN] <0x0x7f10a06734b0[WOWatchDogChild]> pid
2832 has been hanging in the same request for 3 minutes
Sep 08 06:31:45 sogod [2781]: [WARN] <0x0x7f10a0638db0[WOWatchDogChild]> pid
2827 has been hanging in the same request for 3 minutes
But we are NOT getting any "No child available to handle incoming request!"
Does that usually mean that we have created enough workers, and many of
them are nicely occupied for xx minutes in an ActiveSync equivalent of
"imap-IDLE"?
An example is:
Sep 08 09:52:05 sogod [14987]: |SOGo| starting method 'POST' on uri
'/SOGo/Microsoft-Server-ActiveSync?Cmd=Ping&User=username&DeviceId=SEC01B5B&DeviceType=SAMSUNGSMT530'
Sep 08 09:52:05 sogod [14987]: <0x0x7f10a41854a0[SOGoActiveSyncDispatcher]>
Sleeping 30 seconds while detecting changes in Ping...
Sep 08 09:52:35 sogod [14987]: <0x0x7f10a41854a0[SOGoActiveSyncDispatcher]>
Sleeping 30 seconds while detecting changes in Ping...
Sep 08 09:53:05 sogod [2781]: [WARN] <0x0x7f10a0760280[WOWatchDogChild]> pid
14987 has been hanging in the same request for 1 minutes
Sep 08 09:53:05 sogod [14987]: <0x0x7f10a41854a0[SOGoActiveSyncDispatcher]>
Sleeping 30 seconds while detecting changes in Ping...
Sep 08 09:53:35 sogod [14987]: <0x0x7f10a41854a0[SOGoActiveSyncDispatcher]>
Sleeping 30 seconds while detecting changes in Ping...
Sep 08 09:54:05 sogod [2781]: [WARN] <0x0x7f10a0760280[WOWatchDogChild]> pid
14987 has been hanging in the same request for 2 minutes
Sep 08 09:54:06 sogod [14987]: <0x0x7f10a41854a0[SOGoActiveSyncDispatcher]>
Sleeping 30 seconds while detecting changes in Ping...
Sep 08 09:54:36 sogod [14987]: <0x0x7f10a41854a0[SOGoActiveSyncDispatcher]>
Sleeping 30 seconds while detecting changes in Ping...
Sep 08 09:55:05 sogod [2781]: [WARN] <0x0x7f10a0760280[WOWatchDogChild]> pid
14987 has been hanging in the same request for 3 minutes
Sep 08 09:55:06 sogod [14987]: <0x0x7f10a41854a0[SOGoActiveSyncDispatcher]>
Sleeping 30 seconds while detecting changes in Ping...
Sep 08 09:55:36 sogod [14987]: <0x0x7f10a41854a0[SOGoActiveSyncDispatcher]>
Sleeping 30 seconds while detecting changes in Ping...
Sep 08 09:56:05 sogod [2781]: [WARN] <0x0x7f10a0760280[WOWatchDogChild]> pid
14987 has been hanging in the same request for 4 minutes
Sep 08 09:56:06 sogod [14987]: <0x0x7f10a41854a0[SOGoActiveSyncDispatcher]>
Sleeping 30 seconds while detecting changes in Ping...
Sep 08 09:56:36 sogod [14987]: <0x0x7f10a41854a0[SOGoActiveSyncDispatcher]>
Sleeping 30 seconds while detecting changes in Ping...
Sep 08 09:57:05 sogod [2781]: [WARN] <0x0x7f10a0760280[WOWatchDogChild]> pid
14987 has been hanging in the same request for 5 minutes
Sep 08 09:57:06 sogod [14987]: <0x0x7f10a41854a0[SOGoActiveSyncDispatcher]>
Sleeping 30 seconds while detecting changes in Ping...
Sep 08 09:57:36 sogod [14987]: <0x0x7f10a41854a0[SOGoActiveSyncDispatcher]>
Sleeping 30 seconds while detecting changes in Ping...
Sep 08 09:58:05 sogod [2781]: [WARN] <0x0x7f10a0760280[WOWatchDogChild]> pid
14987 has been hanging in the same request for 6 minutes
Sep 08 09:58:06 sogod [14987]: <0x0x7f10a41854a0[SOGoActiveSyncDispatcher]>
Sleeping 30 seconds while detecting changes in Ping...
Sep 08 09:58:36 sogod [14987]: <0x0x7f10a41854a0[SOGoActiveSyncDispatcher]>
Sleeping 30 seconds while detecting changes in Ping...
Sep 08 09:59:05 sogod [2781]: [WARN] <0x0x7f10a0760280[WOWatchDogChild]> pid
14987 has been hanging in the same request for 7 minutes
Sep 08 09:59:06 sogod [14987]: <0x0x7f10a41854a0[SOGoActiveSyncDispatcher]>
Sleeping 30 seconds while detecting changes in Ping...
Sep 08 09:59:36 sogod [14987]: |SOGo| request took 451.652482 seconds to execute
Does the above mean: 451 seconds of activesync IDLEing, until after 451
seconds someting occured that needed to be synced to the device?
For the record: I cannot image ANY operation that would *actually* keep
our server occupied for 451 seconds. (imap is local, very quick, enough
memory, system is not too buzy, ebough memory, no swap usage, etc, etc)
Have a nice weekend to all,
MJ
--
users@sogo.nu
https://inverse.ca/sogo/lists