Jan 29 10:33:27 sogod [4101]: version 2.3.5 (build root@shiva.inverse 201601051534) -- starting Jan 29 10:33:27 sogod [4101]: vmem size check enabled: shutting down app when vmem > 384 MB Jan 29 10:33:27 sogod [4101]: <0x0xb90c329c[SOGoProductLoader]> SOGo products loaded from '/usr/lib/GNUstep/SOGo': Jan 29 10:33:27 sogod [4101]: <0x0xb90c329c[SOGoProductLoader]> CommonUI.SOGo, Appointments.SOGo, ActiveSync.SOGo, MainUI.SOGo, MailerUI.SOGo, AdministrationUI.SOGo, PreferencesUI.SOGo, Mailer.SOGo, Contacts.SOGo, SchedulerUI.SOGo, MailPartViewers.SOGo, ContactsUI.SOGo Jan 29 10:33:27 sogod [4101]: <0x0xb9191094[WOWatchDog]> listening on 127.0.0.1:20000 Jan 29 10:33:27 sogod [4101]: <0x0xb9191094[WOWatchDog]> watchdog process pid: 4101 Jan 29 10:33:27 sogod [4101]: <0x0xb74298c0[WOWatchDogChild]> watchdog request timeout set to 10 minutes Jan 29 10:33:27 sogod [4101]: <0x0xb9191094[WOWatchDog]> preparing 3 children Jan 29 10:33:27 sogod [4101]: <0x0xb9191094[WOWatchDog]> child spawned with pid 4102 Jan 29 10:33:27 sogod [4101]: <0x0xb9191094[WOWatchDog]> child spawned with pid 4103 Jan 29 10:33:27 sogod [4101]: <0x0xb9191094[WOWatchDog]> child spawned with pid 4104 Jan 29 10:34:48 sogod [4104]: <0x0xb90cab24[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 29 10:34:48 sogod [4104]: <0x0xb90cab24[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 29 10:34:48 sogod [4104]: <0x0xb902ae84[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mond_XXX_local_e95_1c5b67eb_1 Jan 29 10:34:48 sogod [4104]: [WARN] <0x0xb935462c[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 29 10:34:48 sogod [4104]: [WARN] <0x0xb935462c[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 29 10:34:48 sogod [4104]: [WARN] <0x0xb935462c[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 29 10:34:48 sogod [4104]: <0x0xb902ae84[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 mond_XXX_local_e95_1c5b67eb_1 0 Jan 29 10:34:48 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.408 - - 2M Jan 29 10:34:48 sogod [4104]: <0x0xb91644f4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mail%2F5036bb35162fab56c80e0000b79f221a 3 1-1 vevent%2Fpersonal 4 1454059627 vcard%2Fpersonal 0 1454059537 mail%2F981b3a01552fab56d60e0000b79f221a 3 2-1 Jan 29 10:34:49 sogod [4104]: <0x0xb935cc44[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 29 10:34:49 sogod [4104]: <0x0xb935cc44[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 29 10:34:49 sogod [4104]: <0x0xb91644f4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 Email mail%2F5036bb35162fab56c80e0000b79f221a 0 1 Calendar vevent%2Fpersonal 1 1 Contacts vcard%2Fpersonal 1 1 Email mail%2F981b3a01552fab56d60e0000b79f221a 1 Jan 29 10:34:49 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=GetItemEstimate HTTP/1.1" 200 233/221 0.673 - - 144K Jan 29 10:34:49 sogod [4104]: <0x0xb936d9dc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454059627 vevent%2Fpersonal 5 4 1 2048 1 Jan 29 10:34:49 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/83 0.015 - - 40K Jan 29 10:34:49 sogod [4104]: <0x0xb935fb84[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454059537 vcard%2Fpersonal 5 0 1 1024 1 Jan 29 10:34:49 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/82 0.012 - - 0 Jan 29 10:34:49 sogod [4104]: <0x0xb9354174[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 2-1 mail%2F981b3a01552fab56d60e0000b79f221a 5 3 2 2048 1 Jan 29 10:34:50 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/98 0.409 - - 0 Jan 29 10:34:50 sogod [4104]: <0x0xb92f2b64[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: RnJvbTogInRlc3R1c2VyQGl2YmhvbWUubG9jYWwiIDx0ZXN0dXNlckBpdmJob21lLmxvY2FsPg0KVG86IDxhZG1pbkBpdmJob21lLmR5bmRucy5vcmc+DQpTdWJqZWN0OiBUZXN0DQpEYXRlOiBGcmksIDI5IEphbiAyMDE2IDEwOjM0OjQ4ICswMTAwDQpNZXNzYWdlLUlEOiA8UVNXcUs1V3JQQ1JTQE05UnpwQ2toPg0KWC1NYWlsZXI6IEVBUyBWZXJzaW9uIDEuMDANCk1JTUUtVmVyc2lvbjogMS4wDQpDb250ZW50LUxhbmd1YWdlOiBpLWRlZmF1bHQNCkNvbnRlbnQtVHlwZTogbXVsdGlwYXJ0L21peGVkOw0KICBib3VuZGFyeT0iRVBPQzMyLUxQRjk3N1RDNUsrLGIrbWptcTV5VkRDMi0rVENMZzVTSFhzZlI2MWo1OTI0QksncyINCg0KVGhpcyBpcyBhIE1JTUUgTWVzc2FnZQ0KDQotLUVQT0MzMi1MUEY5NzdUQzVLKyxiK21qbXE1eVZEQzItK1RDTGc1U0hYc2ZSNjFqNTkyNEJLJ3MNCkNvbnRlbnQtVHlwZTogdGV4dC9wbGFpbjsgY2hhcnNldD1VVEYtOA0KQ29udGVudC1EaXNwb3NpdGlvbjogaW5saW5lDQpDb250ZW50LVRyYW5zZmVyLUVuY29kaW5nOiBxdW90ZWQtcHJpbnRhYmxlDQoNClRlc3QNCi0tRVBPQzMyLUxQRjk3N1RDNUsrLGIrbWptcTV5VkRDMi0rVENMZzVTSFhzZlI2MWo1OTI0Qksncy0tDQo= Jan 29 10:35:11 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&SaveInSent=T&Cmd=SendMail HTTP/1.1" 200 0/629 21.071 - - 332K Jan 29 10:35:12 sogod [4104]: <0x0xb9097024[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 2-1 mail%2F981b3a01552fab56d60e0000b79f221a 5 3 2 2048 1 Jan 29 10:35:13 sogod [4104]: <0x0xb9319f14[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 29 10:35:13 sogod [4104]: <0x0xb9097024[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (1) - lastObject: 1-1 Jan 29 10:35:13 sogod [4104]: <0x0xb9097024[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (2) - lastObject: 2-2 Jan 29 10:35:13 sogod [4104]: <0x0xb9097024[SOGoActiveSyncDispatcher]> EAS - found in cache: 0 k = 0 Jan 29 10:35:13 sogod [4104]: <0x0xb9097024[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 1-1 Jan 29 10:35:13 sogod [4104]: <0x0xb9097024[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 2-2 Jan 29 10:35:13 sogod [4104]: <0x0xb9097024[SOGoActiveSyncDispatcher]> EAS - ADD! Jan 29 10:35:13 sogod [4104]: <0x0xb9097024[SOGoActiveSyncDispatcher]> EAS - first uid added 2 Jan 29 10:35:13 sogod [4104]: <0x0xb9097024[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 29 10:35:13 sogod [4104]: <0x0xb9097024[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Email 2-2 mail%2F981b3a01552fab56d60e0000b79f221a 1 2 "admin@XXX.dyndns.org" <admin@XXX.dyndns.org> "ein testuser" <testuser@XXX.local> Test Test 2016-01-29T09:34:48.000Z testuser 1 1 IPM.Note urn:content-classes:message 65001 1 0 Test 4 0 1 Jan 29 10:35:13 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 350/98 0.772 - - 292K Jan 29 10:35:20 sogod [4104]: <0x0xb91d61b4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mond_XXX_local_e95_1c5b67eb_1 Jan 29 10:35:21 sogod [4104]: <0x0xb91d61b4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 mond_XXX_local_e95_1c5b67eb_1 0 Jan 29 10:35:21 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.336 - - 68K Jan 29 10:35:21 sogod [4104]: <0x0xb939166c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1-1 mail%2F5036bb35162fab56c80e0000b79f221a 5 3 2 2048 1 Jan 29 10:35:21 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/98 0.406 - - 0 Jan 29 10:35:22 sogod [4104]: <0x0xb935c104[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454059627 vevent%2Fpersonal 5 4 1 2048 1 Jan 29 10:35:22 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/83 0.017 - - 0 Jan 29 10:35:22 sogod [4104]: <0x0xb9164634[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454059537 vcard%2Fpersonal 5 0 1 1024 1 Jan 29 10:35:22 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/82 0.013 - - 0 Jan 29 10:35:22 sogod [4104]: <0x0xb93931d4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 2-2 mail%2F981b3a01552fab56d60e0000b79f221a 5 3 2 2048 1 Jan 29 10:35:23 sogod [4104]: <0x0xb933af4c[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 29 10:35:23 sogod [4104]: <0x0xb93931d4[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (2) - lastObject: 2-2 Jan 29 10:35:23 sogod [4104]: <0x0xb93931d4[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (1) - lastObject: 2-2 Jan 29 10:35:23 sogod [4104]: <0x0xb93931d4[SOGoActiveSyncDispatcher]> EAS - found in cache: 0 k = 0 Jan 29 10:35:23 sogod [4104]: <0x0xb93931d4[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 2-2 Jan 29 10:35:23 sogod [4104]: <0x0xb93931d4[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 29 10:35:23 sogod [4104]: <0x0xb93931d4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Email 3-2 mail%2F981b3a01552fab56d60e0000b79f221a 1 Jan 29 10:35:23 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 74/98 0.661 - - 0 Jan 29 10:35:23 sogod [4104]: <0x0xb9333b44[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:35:23 sogod [4104]: <0x0xb9333b44[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:35:33 sogod [4104]: <0x0xb9333b44[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:35:33 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.503 - - 8K Jan 29 10:35:35 sogod [4104]: <0x0xb936fe84[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:35:35 sogod [4104]: <0x0xb936fe84[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:35:35 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.015 - - 0 Jan 29 10:35:36 sogod [4104]: <0x0xb9028f5c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:35:37 sogod [4104]: <0x0xb9028f5c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:35:47 sogod [4104]: <0x0xb9028f5c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:35:47 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.498 - - 0 Jan 29 10:35:47 sogod [4104]: <0x0xb93359e4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:35:47 sogod [4104]: <0x0xb93359e4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:35:47 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.007 - - 0 Jan 29 10:35:47 sogod [4104]: <0x0xb9355b3c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:35:48 sogod [4104]: <0x0xb9355b3c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:35:56 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 10:35:56 sogod [4102]: <0x0xb902319c[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 29 10:35:56 sogod [4103]: <0x0xb92cd59c[SOGoCache]> Cache cleanup interval set every 1800.000000 secondsJan 29 10:35:56 sogod [4102]: <0x0xb902319c[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 29 10:35:56 sogod [4103]: <0x0xb92cd59c[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 29 10:35:56 sogod [4103]: [WARN] <0x0xb930297c[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 29 10:35:56 sogod [4103]: [WARN] <0x0xb930297c[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 29 10:35:56 sogod [4103]: [WARN] <0x0xb930297c[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 29 10:35:56 sogod [4102]: [WARN] <0x0xb9361cec[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 29 10:35:56 sogod [4102]: [WARN] <0x0xb9361cec[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 29 10:35:56 sogod [4102]: [WARN] <0x0xb9361cec[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 29 10:35:56 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454060156 HTTP/1.1" 200 57/0 0.150 - - 988K Jan 29 10:35:56 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 10:35:56 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.148 - - 1M Jan 29 10:35:56 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 10:35:56 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160130&filterpopup=view_today HTTP/1.1" 200 2/0 0.012 - - 68K Jan 29 10:35:56 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/taskslist?show-completed=0&asc=true&sort=end&filterpopup=view_today HTTP/1.1" 200 2/0 0.007 - - 4K Jan 29 10:35:56 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview?day=20160130 HTTP/1.1" 200 2273/0 0.112 54908 95% 1M Jan 29 10:35:56 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 359/0 0.009 - - 64K Jan 29 10:35:58 sogod [4104]: <0x0xb9355b3c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:35:58 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.495 - - 0 Jan 29 10:35:58 sogod [4104]: <0x0xb9339c4c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:35:58 sogod [4104]: <0x0xb9339c4c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:35:58 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.005 - - 0 Jan 29 10:35:58 sogod [4104]: <0x0xb92f8654[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:35:59 sogod [4104]: <0x0xb92f8654[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:36:09 sogod [4104]: <0x0xb92f8654[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:36:09 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.510 - - 0 Jan 29 10:36:11 sogod [4104]: <0x0xb939304c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:36:11 sogod [4104]: <0x0xb939304c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:36:11 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.008 - - 0 Jan 29 10:36:11 sogod [4104]: <0x0xb9336324[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:36:12 sogod [4104]: <0x0xb9336324[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:36:22 sogod [4104]: <0x0xb9336324[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:36:22 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.507 - - 0 Jan 29 10:36:22 sogod [4104]: <0x0xb92fdab4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:36:22 sogod [4104]: <0x0xb92fdab4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:36:22 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.005 - - 0 Jan 29 10:36:22 sogod [4104]: <0x0xb93724e4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:36:23 sogod [4104]: <0x0xb93724e4[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:36:33 sogod [4104]: <0x0xb93724e4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:36:33 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.495 - - 0 Jan 29 10:36:33 sogod [4104]: <0x0xb90d268c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:36:33 sogod [4104]: <0x0xb90d268c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:36:33 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.015 - - 0 Jan 29 10:36:36 sogod [4104]: <0x0xb902e9bc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:36:37 sogod [4104]: <0x0xb902e9bc[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:36:47 sogod [4104]: <0x0xb902e9bc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:36:47 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.493 - - 16K Jan 29 10:36:47 sogod [4104]: <0x0xb93b3d94[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:36:47 sogod [4104]: <0x0xb93b3d94[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:36:47 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.013 - - 0 Jan 29 10:36:49 sogod [4104]: <0x0xb902278c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:36:49 sogod [4104]: <0x0xb902278c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:36:59 sogod [4104]: <0x0xb902278c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:36:59 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.490 - - 0 Jan 29 10:37:00 sogod [4104]: <0x0xb9335acc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:37:00 sogod [4104]: <0x0xb9335acc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:37:00 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.005 - - 0 Jan 29 10:37:00 sogod [4104]: <0x0xb93692a4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:37:01 sogod [4104]: <0x0xb93692a4[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:37:11 sogod [4104]: <0x0xb93692a4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:37:11 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.490 - - 0 Jan 29 10:37:11 sogod [4104]: <0x0xb936dcc4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:37:11 sogod [4104]: <0x0xb936dcc4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:37:11 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.004 - - 0 Jan 29 10:37:11 sogod [4104]: <0x0xb9338384[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:37:12 sogod [4104]: <0x0xb9338384[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:37:22 sogod [4104]: <0x0xb9338384[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:37:22 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.486 - - 0 Jan 29 10:37:22 sogod [4104]: <0x0xb92a9ff4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:37:22 sogod [4104]: <0x0xb92a9ff4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:37:22 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.014 - - 0 Jan 29 10:37:24 sogod [4104]: <0x0xb9311ee4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:37:25 sogod [4104]: <0x0xb9311ee4[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:37:25 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454060246 HTTP/1.1" 200 57/0 0.005 - - 8K Jan 29 10:37:25 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 10:37:25 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/taskslist?show-completed=0&asc=true&sort=end&filterpopup=view_today HTTP/1.1" 200 2/0 0.012 - - 8K Jan 29 10:37:25 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.005 - - 0 Jan 29 10:37:25 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/batchDelete?ids=E95-56AB3000-3-16AE9120.ics HTTP/1.1" 204 0/0 0.068 - - 800K Jan 29 10:37:35 sogod [4104]: <0x0xb9311ee4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:37:35 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.500 - - 8K Jan 29 10:37:35 sogod [4104]: <0x0xb93b8ce4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:37:35 sogod [4104]: <0x0xb93b8ce4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:37:35 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.004 - - 0 Jan 29 10:37:36 sogod [4104]: <0x0xb93226f4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:37:36 sogod [4104]: <0x0xb93226f4[SOGoActiveSyncDispatcher]> Change detected using Ping, we let the EAS client know to send a Sync. Jan 29 10:37:36 sogod [4104]: <0x0xb93226f4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 2 vevent%2Fpersonal Jan 29 10:37:36 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 36/192 0.492 - - 8K Jan 29 10:37:37 sogod [4104]: <0x0xb93188cc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454059627 vevent%2Fpersonal 5 4 1 2048 1 Jan 29 10:37:38 sogod [4104]: <0x0xb93188cc[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 29 10:37:38 sogod [4104]: <0x0xb93188cc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar 1454060245 vevent%2Fpersonal 1 E95-56AB3000-3-16AE9120 Jan 29 10:37:38 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 93/83 0.015 - - 24K Jan 29 10:37:38 sogod [4104]: <0x0xb9428484[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:37:38 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/0 0.073 - - 648K Jan 29 10:37:39 sogod [4104]: <0x0xb93c9924[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:37:39 sogod [4104]: <0x0xb93c9924[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:37:49 sogod [4104]: <0x0xb93c9924[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:37:49 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.488 - - 0 Jan 29 10:37:50 sogod [4104]: <0x0xb9411324[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:37:50 sogod [4104]: <0x0xb9411324[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:37:50 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.006 - - 0 Jan 29 10:37:50 sogod [4104]: <0x0xb9410de4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:37:50 sogod [4104]: <0x0xb9410de4[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:38:00 sogod [4104]: <0x0xb9410de4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:38:00 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.496 - - 0 Jan 29 10:38:01 sogod [4104]: <0x0xb93a8ccc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:38:01 sogod [4104]: <0x0xb93a8ccc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:38:01 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.005 - - 0 Jan 29 10:38:01 sogod [4104]: <0x0xb93dfe6c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:38:02 sogod [4104]: <0x0xb93dfe6c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:38:12 sogod [4104]: <0x0xb93dfe6c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:38:12 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.549 - - 0 Jan 29 10:38:12 sogod [4104]: <0x0xb941bc8c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:38:12 sogod [4104]: <0x0xb941bc8c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:38:12 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.004 - - 0 Jan 29 10:38:13 sogod [4104]: <0x0xb93f5354[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:38:13 sogod [4104]: <0x0xb93f5354[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:38:14 sogod [4103]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/newevent?day=20160130&hm=1100 HTTP/1.1" 302 0/0 0.011 - - 44K Jan 29 10:38:15 sogod [4103]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal//1007-56AB3300-3-5DD63480.ics/editAsAppointment?day=20160130&hm=1100 HTTP/1.1" 200 10434/0 0.105 33011 68% 1M Jan 29 10:38:23 sogod [4104]: <0x0xb93f5354[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:38:23 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.503 - - 0 Jan 29 10:38:24 sogod [4104]: <0x0xb93d75f4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:38:24 sogod [4104]: <0x0xb93d75f4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:38:24 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.016 - - 0 Jan 29 10:38:25 sogod [4104]: <0x0xb93f725c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:38:26 sogod [4104]: <0x0xb93f725c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:38:29 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/1007-56AB3300-3-5DD63480.ics/saveAsAppointment HTTP/1.1" 200 21/814 0.021 - - 92K Jan 29 10:38:29 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 10:38:29 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454060309 HTTP/1.1" 200 58/0 0.011 - - 0 Jan 29 10:38:29 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160130&filterpopup=view_today HTTP/1.1" 200 2/0 0.011 - - 12K Jan 29 10:38:29 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview?day=20160130 HTTP/1.1" 200 2273/0 0.095 54908 95% 1M Jan 29 10:38:29 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 353/0 0.011 - - 16K Jan 29 10:38:36 sogod [4104]: <0x0xb93f725c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:38:36 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.502 - - 0 Jan 29 10:38:36 sogod [4104]: <0x0xb941b83c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:38:36 sogod [4104]: <0x0xb941b83c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:38:36 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.015 - - 0 Jan 29 10:38:38 sogod [4104]: <0x0xb93d396c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:38:38 sogod [4104]: <0x0xb93d396c[SOGoActiveSyncDispatcher]> Change detected using Ping, we let the EAS client know to send a Sync. Jan 29 10:38:38 sogod [4104]: <0x0xb93d396c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 2 vevent%2Fpersonal Jan 29 10:38:38 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 36/193 0.492 - - 0 Jan 29 10:38:40 sogod [4104]: <0x0xb915bdbc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454060245 vevent%2Fpersonal 5 4 1 2048 1 Jan 29 10:38:40 sogod [4104]: <0x0xb915bdbc[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 29 10:38:40 sogod [4104]: <0x0xb915bdbc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar 1454060309 vevent%2Fpersonal 1 1007-56AB3300-3-5DD63480 0 20160129T093829Z 20160130T100000Z 20160130T110000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 test event not defined 1007-56AB3300-3-5DD63480 0 15 1 Jan 29 10:38:40 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 479/83 0.033 - - 504K Jan 29 10:38:41 sogod [4104]: <0x0xb940c19c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:38:41 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/0 0.007 - - 0 Jan 29 10:38:42 sogod [4104]: <0x0xb93d8164[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:38:43 sogod [4104]: <0x0xb93d8164[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:38:53 sogod [4104]: <0x0xb93d8164[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:38:53 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.509 - - 0 Jan 29 10:38:54 sogod [4104]: <0x0xb93631dc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:38:54 sogod [4104]: <0x0xb93631dc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:38:54 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.021 - - 0 Jan 29 10:38:54 sogod [4104]: <0x0xb941185c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:38:54 sogod [4104]: <0x0xb941185c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:39:04 sogod [4104]: <0x0xb941185c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:39:04 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.502 - - 0 Jan 29 10:39:05 sogod [4104]: <0x0xb9392fd4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:39:05 sogod [4104]: <0x0xb9392fd4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:39:05 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.005 - - 0 Jan 29 10:39:07 sogod [4104]: <0x0xb93dbfec[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:39:07 sogod [4104]: <0x0xb93dbfec[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:39:17 sogod [4104]: <0x0xb93dbfec[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:39:17 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.499 - - 0 Jan 29 10:39:18 sogod [4104]: <0x0xb936a09c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:39:18 sogod [4104]: <0x0xb936a09c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:39:18 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.016 - - 0 Jan 29 10:39:20 sogod [4104]: <0x0xb940df64[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:39:21 sogod [4104]: <0x0xb940df64[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:39:31 sogod [4104]: <0x0xb940df64[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:39:31 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.503 - - 0 Jan 29 10:39:32 sogod [4104]: <0x0xb93f828c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:39:32 sogod [4104]: <0x0xb93f828c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:39:32 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.015 - - 0 Jan 29 10:39:32 sogod [4104]: <0x0xb9035214[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:39:32 sogod [4104]: <0x0xb9035214[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:39:42 sogod [4104]: <0x0xb9035214[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:39:42 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.500 - - 0 Jan 29 10:39:43 sogod [4104]: <0x0xb93daf14[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:39:43 sogod [4104]: <0x0xb93daf14[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:39:43 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.019 - - 0 Jan 29 10:39:44 sogod [4104]: <0x0xb933a104[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:39:45 sogod [4104]: <0x0xb933a104[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:39:55 sogod [4104]: <0x0xb933a104[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:39:55 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.494 - - 0 Jan 29 10:39:55 sogod [4104]: <0x0xb93e08dc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:39:55 sogod [4104]: <0x0xb93e08dc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:39:55 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.008 - - 0 Jan 29 10:39:55 sogod [4104]: <0x0xb93d51b4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:39:56 sogod [4104]: <0x0xb93d51b4[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:40:06 sogod [4104]: <0x0xb93d51b4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:40:06 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.493 - - 0 Jan 29 10:40:06 sogod [4104]: <0x0xb939122c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:40:06 sogod [4104]: <0x0xb939122c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:40:06 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.014 - - 0 Jan 29 10:40:06 sogod [4104]: <0x0xb93c6684[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:40:07 sogod [4104]: <0x0xb93c6684[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:40:17 sogod [4104]: <0x0xb93c6684[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:40:17 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.504 - - 0 Jan 29 10:40:17 sogod [4104]: <0x0xb94278f4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:40:17 sogod [4104]: <0x0xb94278f4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:40:17 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.014 - - 0 Jan 29 10:40:19 sogod [4104]: <0x0xb93a9f8c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:40:20 sogod [4104]: <0x0xb93a9f8c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:40:30 sogod [4104]: <0x0xb93a9f8c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:40:30 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.491 - - 0 Jan 29 10:40:31 sogod [4104]: <0x0xb9410fb4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:40:31 sogod [4104]: <0x0xb9410fb4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:40:31 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.015 - - 0 Jan 29 10:40:31 sogod [4104]: <0x0xb93ca474[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:40:31 sogod [4104]: <0x0xb93ca474[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:40:41 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/1007-56AB3300-3-5DD63480.ics/view HTTP/1.1" 200 293/0 0.031 - - 536K Jan 29 10:40:41 sogod [4104]: <0x0xb93ca474[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:40:41 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.497 - - 0 Jan 29 10:40:42 sogod [4104]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/1007-56AB3300-3-5DD63480.ics/edit HTTP/1.1" 200 10442/0 0.161 33027 68% 2M Jan 29 10:40:42 sogod [4104]: <0x0xb93f70a4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:40:42 sogod [4104]: <0x0xb93f70a4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:40:42 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.016 - - 0 Jan 29 10:40:45 sogod [4104]: <0x0xb941241c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:40:46 sogod [4104]: <0x0xb941241c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:40:56 sogod [4104]: <0x0xb941241c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:40:56 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.502 - - 0 Jan 29 10:40:56 sogod [4104]: <0x0xb947db34[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:40:56 sogod [4104]: <0x0xb947db34[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:40:56 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.015 - - 0 Jan 29 10:40:58 sogod [4104]: <0x0xb94108ec[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:40:59 sogod [4104]: <0x0xb94108ec[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:40:59 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/1007-56AB3300-3-5DD63480.ics/saveAsAppointment HTTP/1.1" 200 21/819 0.107 - - 1M Jan 29 10:40:59 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454060460 HTTP/1.1" 200 58/0 0.004 - - 4K Jan 29 10:40:59 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview?day=20160130 HTTP/1.1" 200 2273/0 0.032 54908 95% 12K Jan 29 10:41:00 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160130&filterpopup=view_today HTTP/1.1" 200 2/0 0.008 - - 0 Jan 29 10:41:00 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 353/0 0.007 - - 4K Jan 29 10:41:09 sogod [4104]: <0x0xb94108ec[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:41:09 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.508 - - 0 Jan 29 10:41:09 sogod [4104]: <0x0xb9410004[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:41:09 sogod [4104]: <0x0xb9410004[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:41:09 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.017 - - 0 Jan 29 10:41:09 sogod [4104]: <0x0xb93d45a4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:41:10 sogod [4104]: <0x0xb93d45a4[SOGoActiveSyncDispatcher]> Change detected using Ping, we let the EAS client know to send a Sync. Jan 29 10:41:10 sogod [4104]: <0x0xb93d45a4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 2 vevent%2Fpersonal Jan 29 10:41:10 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 36/193 0.504 - - 0 Jan 29 10:41:12 sogod [4104]: <0x0xb918f44c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454060309 vevent%2Fpersonal 5 4 1 2048 1 Jan 29 10:41:12 sogod [4104]: <0x0xb918f44c[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 29 10:41:12 sogod [4104]: <0x0xb918f44c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar 1454060459 vevent%2Fpersonal 1 1007-56AB3300-3-5DD63480 0 20160129T093829Z 20160130T100000Z 20160130T110000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 test event not defined 1007-56AB3300-3-5DD63480 0 15 1 Jan 29 10:41:12 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 479/83 0.020 - - 0 Jan 29 10:41:14 sogod [4104]: <0x0xb94b6264[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:41:14 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/0 0.005 - - 0 Jan 29 10:41:14 sogod [4104]: <0x0xb93dfa44[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:41:15 sogod [4104]: <0x0xb93dfa44[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:41:25 sogod [4104]: <0x0xb93dfa44[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:41:25 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.496 - - 0 Jan 29 10:41:25 sogod [4104]: <0x0xb94a46dc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:41:25 sogod [4104]: <0x0xb94a46dc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:41:25 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.012 - - 0 Jan 29 10:41:25 sogod [4104]: <0x0xb9523c74[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:41:26 sogod [4104]: <0x0xb9523c74[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:41:36 sogod [4104]: <0x0xb9523c74[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:41:36 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.505 - - 0 Jan 29 10:41:36 sogod [4104]: <0x0xb94c07bc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:41:36 sogod [4104]: <0x0xb94c07bc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:41:36 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.017 - - 0 Jan 29 10:41:36 sogod [4104]: <0x0xb93a7e94[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:41:37 sogod [4104]: <0x0xb93a7e94[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:41:47 sogod [4104]: <0x0xb93a7e94[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:41:47 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.502 - - 0 Jan 29 10:41:47 sogod [4104]: <0x0xb903b06c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:41:47 sogod [4104]: <0x0xb903b06c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:41:47 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.014 - - 0 Jan 29 10:41:49 sogod [4104]: 192.168.10.181 "GET /SOGo/so/testuser/preferences HTTP/1.1" 200 18271/0 0.426 79927 77% 1M Jan 29 10:41:50 sogod [4104]: <0x0xb943707c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:41:50 sogod [4104]: <0x0xb943707c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:42:00 sogod [4104]: <0x0xb943707c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:42:00 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.502 - - 0 Jan 29 10:42:01 sogod [4104]: <0x0xb95803fc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:42:01 sogod [4104]: <0x0xb95803fc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:42:01 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.014 - - 0 Jan 29 10:42:02 sogod [4104]: <0x0xb93a886c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:42:03 sogod [4104]: <0x0xb93a886c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:42:13 sogod [4104]: <0x0xb93a886c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:42:13 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.495 - - 0 Jan 29 10:42:13 sogod [4104]: <0x0xb93dbe94[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:42:13 sogod [4104]: <0x0xb93dbe94[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:42:13 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.014 - - 0 Jan 29 10:42:15 sogod [4104]: <0x0xb92fba94[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:42:15 sogod [4104]: <0x0xb92fba94[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:42:25 sogod [4104]: <0x0xb92fba94[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:42:25 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.502 - - 0 Jan 29 10:42:26 sogod [4104]: <0x0xb943196c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:42:26 sogod [4104]: <0x0xb943196c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:42:26 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.012 - - 0 Jan 29 10:42:29 sogod [4104]: <0x0xb955768c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:42:29 sogod [4104]: <0x0xb955768c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:42:39 sogod [4104]: <0x0xb955768c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:42:39 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.497 - - 0 Jan 29 10:42:40 sogod [4104]: <0x0xb948fa9c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:42:40 sogod [4104]: <0x0xb948fa9c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:42:40 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.013 - - 0 Jan 29 10:42:41 sogod [4104]: <0x0xb93c940c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:42:42 sogod [4104]: <0x0xb93c940c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:42:52 sogod [4104]: <0x0xb93c940c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:42:52 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.504 - - 0 Jan 29 10:42:52 sogod [4104]: <0x0xb94918cc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:42:52 sogod [4104]: <0x0xb94918cc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:42:52 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.015 - - 0 Jan 29 10:42:53 sogod [4104]: SOGoUserHomePage user 'testuser' logged off Jan 29 10:42:53 sogod [4104]: 192.168.10.181 "GET /SOGo/so/testuser/logoff HTTP/1.1" 302 0/0 0.012 - - 108K Jan 29 10:42:53 sogod [4104]: 192.168.10.181 "GET /SOGo/so/ HTTP/1.1" 200 4631/0 0.034 13454 65% 24K Jan 29 10:42:54 sogod [4104]: <0x0xb9504c0c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:42:54 sogod [4104]: <0x0xb9504c0c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:43:04 sogod [4104]: <0x0xb9504c0c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:43:04 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.508 - - 0 Jan 29 10:43:05 sogod [4104]: <0x0xb94ed53c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:43:05 sogod [4104]: <0x0xb94ed53c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:43:05 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.005 - - 0 Jan 29 10:43:06 sogod [4104]: <0x0xb94522d4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:43:06 sogod [4104]: <0x0xb94522d4[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:43:16 sogod [4104]: <0x0xb94522d4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:43:16 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.492 - - 0 Jan 29 10:43:16 sogod [4104]: <0x0xb94ee664[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:43:16 sogod [4104]: <0x0xb94ee664[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:43:16 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.013 - - 0 Jan 29 10:43:18 sogod [4104]: <0x0xb94973d4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:43:18 sogod [4104]: <0x0xb94973d4[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:43:28 sogod [4104]: <0x0xb94973d4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:43:28 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.494 - - 0 Jan 29 10:43:30 sogod [4104]: <0x0xb94d7a6c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:43:30 sogod [4104]: <0x0xb94d7a6c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:43:30 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.016 - - 0 Jan 29 10:43:31 sogod [4104]: <0x0xb93dfc6c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:43:31 sogod [4104]: <0x0xb93dfc6c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:43:41 sogod [4104]: <0x0xb93dfc6c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:43:41 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.505 - - 0 Jan 29 10:43:42 sogod [4104]: <0x0xb95576dc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:43:42 sogod [4104]: <0x0xb95576dc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:43:42 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.004 - - 0 Jan 29 10:43:42 sogod [4104]: <0x0xb95d34d4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:43:42 sogod [4104]: <0x0xb95d34d4[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:43:51 sogod [4102]: <0x0xb9492fdc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mond_XXX_local_e95_1c5b67eb_1 Jan 29 10:43:52 sogod [4102]: <0x0xb9492fdc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 mond_XXX_local_e95_1c5b67eb_1 0 Jan 29 10:43:52 sogod [4102]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.352 - - 528K Jan 29 10:43:52 sogod [4104]: <0x0xb95d34d4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:43:52 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.506 - - 0 Jan 29 10:43:53 sogod [4104]: <0x0xb9437d8c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1-1 mail%2F5036bb35162fab56c80e0000b79f221a 5 3 2 2048 1 Jan 29 10:43:53 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/98 0.402 - - 0 Jan 29 10:43:54 sogod [4104]: <0x0xb955d804[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454059537 vcard%2Fpersonal 5 0 1 1024 1 Jan 29 10:43:54 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/82 0.011 - - 0 Jan 29 10:43:55 sogod [4104]: <0x0xb95134e4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 3-2 mail%2F981b3a01552fab56d60e0000b79f221a 5 3 2 2048 1 Jan 29 10:43:56 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/98 0.401 - - 0 Jan 29 10:43:56 sogod [4104]: <0x0xb9531ab4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:43:57 sogod [4104]: <0x0xb9531ab4[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:44:07 sogod [4104]: <0x0xb9531ab4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:44:07 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/158 10.498 - - 0 Jan 29 10:44:07 sogod [4104]: <0x0xb94a856c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:44:07 sogod [4104]: <0x0xb94a856c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:44:07 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.013 - - 0 Jan 29 10:44:09 sogod [4104]: <0x0xb94c401c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:44:09 sogod [4104]: <0x0xb94c401c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:44:19 sogod [4104]: <0x0xb94c401c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:44:19 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/157 10.418 - - 0 Jan 29 10:44:20 sogod [4104]: <0x0xb93ca0ac[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:44:20 sogod [4104]: <0x0xb93ca0ac[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:44:20 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.004 - - 0 Jan 29 10:44:20 sogod [4104]: <0x0xb94ab714[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:44:20 sogod [4104]: <0x0xb94ab714[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:44:30 sogod [4102]: <0x0xb947e4f4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mond_XXX_local_e95_1c5b67eb_1 Jan 29 10:44:30 sogod [4102]: <0x0xb947e4f4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 mond_XXX_local_e95_1c5b67eb_1 0 Jan 29 10:44:30 sogod [4102]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.336 - - 0 Jan 29 10:44:30 sogod [4104]: <0x0xb94ab714[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:44:30 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/158 10.492 - - 0 Jan 29 10:44:30 sogod [4104]: <0x0xb954c29c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1-1 mail%2F5036bb35162fab56c80e0000b79f221a 5 3 2 2048 1 Jan 29 10:44:31 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/98 0.464 - - 0 Jan 29 10:44:31 sogod [4104]: <0x0xb958dce4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 vevent%2Fpersonal Jan 29 10:44:31 sogod [4104]: <0x0xb958dce4[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 29 10:44:31 sogod [4104]: <0x0xb958dce4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar -1 vevent%2Fpersonal 1 Jan 29 10:44:31 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 54/36 0.008 - - 0 Jan 29 10:44:33 sogod [4104]: <0x0xb9504d0c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: -1 vevent%2Fpersonal 5 4 1 2048 1 Jan 29 10:44:33 sogod [4104]: <0x0xb9504d0c[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 29 10:44:33 sogod [4104]: <0x0xb9504d0c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar 1454060459 vevent%2Fpersonal 1 1007-56AB3300-3-5DD63480 0 20160129T093829Z 20160130T100000Z 20160130T110000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 test event not defined 1007-56AB3300-3-5DD63480 0 15 1 Jan 29 10:44:33 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 479/75 0.023 - - 0 Jan 29 10:44:33 sogod [4104]: <0x0xb954c71c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454059537 vcard%2Fpersonal 5 0 1 1024 1 Jan 29 10:44:33 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/82 0.008 - - 0 Jan 29 10:44:34 sogod [4104]: <0x0xb942e524[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 3-2 mail%2F981b3a01552fab56d60e0000b79f221a 5 3 2 2048 1 Jan 29 10:44:35 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/98 0.411 - - 0 Jan 29 10:44:35 sogod [4104]: <0x0xb93d3c64[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:44:35 sogod [4104]: <0x0xb93d3c64[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:44:45 sogod [4104]: <0x0xb93d3c64[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:44:45 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/187 10.519 - - 0 Jan 29 10:44:46 sogod [4104]: <0x0xb93a96c4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:44:46 sogod [4104]: <0x0xb93a96c4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:44:46 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.006 - - 0 Jan 29 10:44:46 sogod [4104]: <0x0xb93d42ac[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:44:47 sogod [4104]: <0x0xb93d42ac[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:44:57 sogod [4104]: <0x0xb93d42ac[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:44:57 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.492 - - 0 Jan 29 10:44:57 sogod [4104]: <0x0xb936ddc4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:44:57 sogod [4104]: <0x0xb936ddc4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:44:57 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.020 - - 0 Jan 29 10:44:57 sogod [4104]: <0x0xb951313c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:44:58 sogod [4104]: <0x0xb951313c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:45:08 sogod [4104]: <0x0xb951313c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:45:08 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.503 - - 0 Jan 29 10:45:08 sogod [4104]: <0x0xb953ae1c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:45:08 sogod [4104]: <0x0xb953ae1c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:45:08 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.017 - - 0 Jan 29 10:45:08 sogod [4104]: <0x0xb95360dc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:45:09 sogod [4104]: <0x0xb95360dc[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:45:19 sogod [4104]: <0x0xb95360dc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:45:19 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.508 - - 0 Jan 29 10:45:19 sogod [4104]: <0x0xb951c2ac[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:45:19 sogod [4104]: <0x0xb951c2ac[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:45:19 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.018 - - 0 Jan 29 10:45:22 sogod [4104]: <0x0xb93ca044[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:45:22 sogod [4104]: <0x0xb93ca044[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:45:32 sogod [4104]: <0x0xb93ca044[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:45:32 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.496 - - 0 Jan 29 10:45:33 sogod [4104]: <0x0xb9493c54[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:45:33 sogod [4104]: <0x0xb9493c54[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:45:33 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.014 - - 0 Jan 29 10:45:33 sogod [4104]: <0x0xb94955a4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:45:33 sogod [4104]: <0x0xb94955a4[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:45:39 sogod [4102]: SOGoRootPage successful login from '192.168.10.181' for user 'testuser' - expire = -1 grace = -1 Jan 29 10:45:39 sogod [4102]: 192.168.10.181 "POST /SOGo/connect HTTP/1.1" 200 27/43 0.022 - - 244K Jan 29 10:45:39 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser HTTP/1.1" 302 0/0 0.002 - - 8K Jan 29 10:45:40 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/view HTTP/1.1" 302 0/0 0.005 - - 8K Jan 29 10:45:40 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/Mail HTTP/1.1" 302 0/0 0.002 - - 0 Jan 29 10:45:40 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/Mail/view HTTP/1.1" 200 11102/0 0.371 39138 71% 772K Jan 29 10:45:40 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454060741 HTTP/1.1" 200 58/0 0.006 - - 0 Jan 29 10:45:41 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/mailboxes HTTP/1.1" 200 341/0 0.228 - - 4K Jan 29 10:45:41 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/foldersState HTTP/1.1" 200 0/0 0.027 - - 52K Jan 29 10:45:41 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderINBOX/unseenCount HTTP/1.1" 200 13/0 0.294 - - 8K Jan 29 10:45:43 sogod [4104]: <0x0xb94955a4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:45:43 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.502 - - 0 Jan 29 10:45:44 sogod [4104]: <0x0xb94ae81c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:45:44 sogod [4104]: <0x0xb94ae81c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:45:44 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.004 - - 0 Jan 29 10:45:44 sogod [4104]: <0x0xb94963a4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:45:45 sogod [4104]: <0x0xb94963a4[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:45:46 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/Mail/0/folderINBOX/expunge HTTP/1.1" 200 2/0 0.236 - - 8K Jan 29 10:45:46 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar HTTP/1.1" 302 0/0 0.005 - - 0 Jan 29 10:45:46 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/view HTTP/1.1" 200 14350/0 0.069 54063 73% 224K Jan 29 10:45:47 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/canAccessContent HTTP/1.1" 204 0/0 0.004 - - 4K Jan 29 10:45:47 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 10:45:47 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454060747 HTTP/1.1" 200 58/0 0.009 - - 0 Jan 29 10:45:47 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 10:45:47 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=&filterpopup=view_today HTTP/1.1" 200 2/0 0.014 - - 4K Jan 29 10:45:47 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/taskslist?show-completed=0&asc=true&sort=end&filterpopup=view_today HTTP/1.1" 200 2/0 0.009 - - 0 Jan 29 10:45:47 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 10:45:47 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.004 - - 4K Jan 29 10:45:47 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview HTTP/1.1" 200 2273/0 0.016 54908 95% 0 Jan 29 10:45:47 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 353/0 0.010 - - 0 Jan 29 10:45:55 sogod [4104]: <0x0xb94963a4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:45:55 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.496 - - 0 Jan 29 10:45:55 sogod [4104]: <0x0xb95f3514[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:45:55 sogod [4104]: <0x0xb95f3514[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:45:55 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.014 - - 0 Jan 29 10:45:57 sogod [4104]: <0x0xb941398c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:45:57 sogod [4104]: <0x0xb941398c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:46:00 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/1007-56AB3300-3-5DD63480.ics/raw HTTP/1.1" 200 826/0 0.006 - - 0 Jan 29 10:46:07 sogod [4104]: <0x0xb941398c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:46:07 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.500 - - 0 Jan 29 10:46:08 sogod [4104]: <0x0xb94c240c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:46:08 sogod [4104]: <0x0xb94c240c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:46:08 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.014 - - 0 Jan 29 10:46:11 sogod [4104]: <0x0xb952e60c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:46:12 sogod [4104]: <0x0xb952e60c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:46:22 sogod [4104]: <0x0xb952e60c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:46:22 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.497 - - 0 Jan 29 10:46:22 sogod [4104]: <0x0xb9410934[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:46:22 sogod [4104]: <0x0xb9410934[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:46:22 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.016 - - 0 Jan 29 10:46:24 sogod [4104]: <0x0xb9397894[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:46:25 sogod [4104]: <0x0xb9397894[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:46:26 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.004 - - 0 Jan 29 10:46:26 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/batchDelete?ids=1007-56AB3300-3-5DD63480.ics HTTP/1.1" 204 0/0 0.015 - - 8K Jan 29 10:46:26 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454060786 HTTP/1.1" 200 2/0 0.009 - - 0 Jan 29 10:46:26 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/taskslist?show-completed=0&asc=true&sort=end&filterpopup=view_today HTTP/1.1" 200 2/0 0.009 - - 0 Jan 29 10:46:35 sogod [4104]: <0x0xb9397894[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:46:35 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.495 - - 0 Jan 29 10:46:35 sogod [4104]: <0x0xb9419c84[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:46:35 sogod [4104]: <0x0xb9419c84[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:46:35 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.004 - - 0 Jan 29 10:46:35 sogod [4104]: <0x0xb9331834[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:46:36 sogod [4104]: <0x0xb9331834[SOGoActiveSyncDispatcher]> Change detected using Ping, we let the EAS client know to send a Sync. Jan 29 10:46:36 sogod [4104]: <0x0xb9331834[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 2 vevent%2Fpersonal Jan 29 10:46:36 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 36/192 0.492 - - 0 Jan 29 10:46:38 sogod [4104]: <0x0xb9421854[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454060459 vevent%2Fpersonal 5 4 1 2048 1 Jan 29 10:46:38 sogod [4104]: <0x0xb9421854[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 29 10:46:38 sogod [4104]: <0x0xb9421854[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar 1454060786 vevent%2Fpersonal 1 1007-56AB3300-3-5DD63480 Jan 29 10:46:38 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 94/83 0.024 - - 0 Jan 29 10:46:38 sogod [4104]: <0x0xb948f664[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:46:38 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/0 0.004 - - 0 Jan 29 10:46:38 sogod [4104]: <0x0xb94a87dc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:46:39 sogod [4104]: <0x0xb94a87dc[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:46:49 sogod [4104]: <0x0xb94a87dc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:46:49 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.494 - - 0 Jan 29 10:46:49 sogod [4104]: <0x0xb94a22fc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:46:49 sogod [4104]: <0x0xb94a22fc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:46:49 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.004 - - 0 Jan 29 10:46:50 sogod [4104]: <0x0xb94e5024[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:46:50 sogod [4104]: <0x0xb94e5024[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:47:00 sogod [4104]: <0x0xb94e5024[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:47:00 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.501 - - 0 Jan 29 10:47:00 sogod [4104]: <0x0xb94eb324[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:47:00 sogod [4104]: <0x0xb94eb324[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:47:00 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.005 - - 0 Jan 29 10:47:01 sogod [4104]: <0x0xb93c48b4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:47:01 sogod [4104]: <0x0xb93c48b4[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:47:11 sogod [4104]: <0x0xb93c48b4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:47:11 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.602 - - 0 Jan 29 10:47:12 sogod [4104]: <0x0xb94c240c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:47:12 sogod [4104]: <0x0xb94c240c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:47:12 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.016 - - 0 Jan 29 10:47:14 sogod [4104]: <0x0xb9546f1c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:47:14 sogod [4104]: <0x0xb9546f1c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:47:24 sogod [4104]: <0x0xb9546f1c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:47:24 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.494 - - 0 Jan 29 10:47:25 sogod [4104]: <0x0xb93f8024[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:47:25 sogod [4104]: <0x0xb93f8024[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:47:25 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.004 - - 0 Jan 29 10:47:26 sogod [4104]: <0x0xb940afb4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:47:27 sogod [4104]: <0x0xb940afb4[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:47:37 sogod [4104]: <0x0xb940afb4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:47:37 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.494 - - 0 Jan 29 10:47:37 sogod [4104]: <0x0xb9507ed4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:47:37 sogod [4104]: <0x0xb9507ed4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:47:37 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.006 - - 0 Jan 29 10:47:39 sogod [4104]: <0x0xb93d61f4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:47:39 sogod [4104]: <0x0xb93d61f4[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:47:49 sogod [4104]: <0x0xb93d61f4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:47:49 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.489 - - 0 Jan 29 10:47:50 sogod [4104]: <0x0xb94bb66c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:47:50 sogod [4104]: <0x0xb94bb66c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:47:50 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.005 - - 0 Jan 29 10:47:52 sogod [4104]: <0x0xb93cd99c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:47:53 sogod [4104]: <0x0xb93cd99c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:48:03 sogod [4104]: <0x0xb93cd99c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:48:03 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.496 - - 0 Jan 29 10:48:03 sogod [4104]: <0x0xb94955e4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:48:03 sogod [4104]: <0x0xb94955e4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:48:03 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.018 - - 0 Jan 29 10:48:05 sogod [4104]: <0x0xb94c63a4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:48:05 sogod [4104]: <0x0xb94c63a4[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:48:11 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 10:48:11 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160129&filterpopup=view_today HTTP/1.1" 200 2/0 0.009 - - 0 Jan 29 10:48:11 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 10:48:11 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454060891 HTTP/1.1" 200 2/0 0.022 - - 0 Jan 29 10:48:11 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 10:48:11 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.007 - - 0 Jan 29 10:48:11 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/taskslist?show-completed=0&asc=true&sort=end&filterpopup=view_today HTTP/1.1" 200 2/0 0.015 - - 0 Jan 29 10:48:11 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview?day=20160129 HTTP/1.1" 200 2273/0 0.014 54908 95% 36K Jan 29 10:48:11 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 64/0 0.006 - - 0 Jan 29 10:48:15 sogod [4104]: <0x0xb94c63a4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:48:15 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.508 - - 0 Jan 29 10:48:16 sogod [4104]: <0x0xb94ba16c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:48:16 sogod [4104]: <0x0xb94ba16c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:48:16 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.003 - - 0 Jan 29 10:48:16 sogod [4104]: <0x0xb94c3394[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:48:17 sogod [4104]: <0x0xb94c3394[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:48:20 sogod [4103]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/1007-56AB3300-3-5DD63480.ics/raw HTTP/1.1" 404 208/0 0.006 - - 0 Jan 29 10:48:26 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454060907 HTTP/1.1" 200 2/0 0.006 - - 0 Jan 29 10:48:26 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160129&filterpopup=view_today HTTP/1.1" 200 2/0 0.005 - - 0 Jan 29 10:48:26 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 10:48:26 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/taskslist?show-completed=0&asc=true&sort=end&filterpopup=view_today HTTP/1.1" 200 2/0 0.013 - - 0 Jan 29 10:48:26 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview?day=20160129 HTTP/1.1" 200 2273/0 0.022 54908 95% 0 Jan 29 10:48:26 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.005 - - 0 Jan 29 10:48:26 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 64/0 0.004 - - 0 Jan 29 10:48:27 sogod [4104]: <0x0xb94c3394[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:48:27 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.495 - - 0 Jan 29 10:48:27 sogod [4104]: <0x0xb93a9554[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:48:27 sogod [4104]: <0x0xb93a9554[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:48:27 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.015 - - 0 Jan 29 10:48:31 sogod [4104]: <0x0xb955702c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:48:31 sogod [4104]: <0x0xb955702c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:48:36 sogod [4103]: <0x0xb9404b6c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mond_XXX_local_e95_1c5b67eb_1 Jan 29 10:48:37 sogod [4103]: <0x0xb9404b6c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 mond_XXX_local_e95_1c5b67eb_1 0 Jan 29 10:48:37 sogod [4103]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.359 - - 516K Jan 29 10:48:37 sogod [4103]: <0x0xb9472c2c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mail%2F5036bb35162fab56c80e0000b79f221a 3 1-1 vevent%2Fpersonal 4 1454060786 vcard%2Fpersonal 0 1454059537 mail%2F981b3a01552fab56d60e0000b79f221a 3 3-2 Jan 29 10:48:37 sogod [4103]: <0x0xb946ae94[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 29 10:48:37 sogod [4103]: <0x0xb946ae94[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 29 10:48:38 sogod [4103]: <0x0xb9472c2c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 Email mail%2F5036bb35162fab56c80e0000b79f221a 0 1 Calendar vevent%2Fpersonal 0 1 Contacts vcard%2Fpersonal 1 1 Email mail%2F981b3a01552fab56d60e0000b79f221a 1 Jan 29 10:48:38 sogod [4103]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=GetItemEstimate HTTP/1.1" 200 233/221 0.648 - - 80K Jan 29 10:48:38 sogod [4103]: <0x0xb940abcc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454060786 vevent%2Fpersonal 5 4 1 2048 1 1 xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAEAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAEAAAAAAAAAxP///w== 20160129T094836Z 20160129T110000Z Test event 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 20160129T120000Z 1 0 2 Jan 29 10:48:38 sogod [4103]: <0x0xb940abcc[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 29 10:48:38 sogod [4103]: <0x0xb940abcc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar 1454060918 vevent%2Fpersonal 1 1 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 1 Jan 29 10:48:38 sogod [4103]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 194/546 0.027 - - 72K Jan 29 10:48:39 sogod [4103]: <0x0xb9484604[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454059537 vcard%2Fpersonal 5 0 1 1024 1 Jan 29 10:48:39 sogod [4103]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/82 0.007 - - 0 Jan 29 10:48:39 sogod [4103]: <0x0xb93e0f6c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 3-2 mail%2F981b3a01552fab56d60e0000b79f221a 5 3 2 2048 1 Jan 29 10:48:40 sogod [4103]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/98 0.410 - - 0 Jan 29 10:48:41 sogod [4103]: <0x0xb940c8bc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mond_XXX_local_e95_1c5b67eb_1 Jan 29 10:48:41 sogod [4103]: <0x0xb940c8bc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 mond_XXX_local_e95_1c5b67eb_1 0 Jan 29 10:48:41 sogod [4103]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.257 - - 0 Jan 29 10:48:41 sogod [4104]: <0x0xb955702c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:48:41 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.497 - - 0 Jan 29 10:48:42 sogod [4104]: <0x0xb930f754[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1-1 mail%2F5036bb35162fab56c80e0000b79f221a 5 3 2 2048 1 Jan 29 10:48:43 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/98 0.406 - - 0 Jan 29 10:48:43 sogod [4104]: <0x0xb95e0b84[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454060786 vevent%2Fpersonal 5 4 1 2048 1 Jan 29 10:48:43 sogod [4104]: <0x0xb95e0b84[SOGoActiveSyncDispatcher]> Cache cleanup needed for device IMEI357909041390858 - user: testuser syncKey: 1454060786 cache: 1454060918 Jan 29 10:48:43 sogod [4104]: <0x0xb95e0b84[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: ADD 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 Jan 29 10:48:43 sogod [4104]: <0x0xb95e0b84[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 29 10:48:43 sogod [4104]: <0x0xb95e0b84[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar 1454060918 vevent%2Fpersonal 1 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 0 20160129T110000Z 20160129T120000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 Test event 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 0 1 Jan 29 10:48:43 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 618/83 0.019 - - 0 Jan 29 10:48:45 sogod [4104]: <0x0xb93dc354[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454059537 vcard%2Fpersonal 5 0 1 1024 1 Jan 29 10:48:45 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/82 0.010 - - 0 Jan 29 10:48:45 sogod [4104]: <0x0xb957d894[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 3-2 mail%2F981b3a01552fab56d60e0000b79f221a 5 3 2 2048 1 Jan 29 10:48:46 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/98 0.407 - - 0 Jan 29 10:48:46 sogod [4104]: <0x0xb95e829c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:48:47 sogod [4104]: <0x0xb95e829c[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:48:53 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 10:48:53 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454060933 HTTP/1.1" 200 2/0 0.012 - - 0 Jan 29 10:48:53 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 10:48:53 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160129&filterpopup=view_today HTTP/1.1" 200 352/0 0.021 - - 0Jan 29 10:48:53 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/taskslist?show-completed=0&asc=true&sort=end&filterpopup=view_today HTTP/1.1" 200 2/0 0.007 - - 0 Jan 29 10:48:53 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 10:48:53 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.010 - - 0 Jan 29 10:48:53 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview?day=20160129 HTTP/1.1" 200 2273/0 0.018 54908 95% 8K Jan 29 10:48:53 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 423/0 0.010 - - 0 Jan 29 10:48:57 sogod [4104]: <0x0xb95e829c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:48:57 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.504 - - 0 Jan 29 10:48:57 sogod [4104]: <0x0xb95d8474[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:48:57 sogod [4104]: <0x0xb95d8474[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:48:57 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.014 - - 0 Jan 29 10:48:57 sogod [4104]: <0x0xb954f4dc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:48:58 sogod [4104]: <0x0xb954f4dc[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:49:02 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/dateselector?day=20160130 HTTP/1.1" 200 766/0 0.014 9375 91% 48K Jan 29 10:49:03 sogod [4103]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/newevent?day=20160130&hm=1300 HTTP/1.1" 302 0/0 0.004 - - 0 Jan 29 10:49:03 sogod [4103]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal//1007-56AB3580-F-5DD63480.ics/editAsAppointment?day=20160130&hm=1300 HTTP/1.1" 200 10433/0 0.031 33011 68% 0 Jan 29 10:49:08 sogod [4104]: <0x0xb954f4dc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:49:08 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.492 - - 0 Jan 29 10:49:08 sogod [4104]: <0x0xb93226f4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:49:08 sogod [4104]: <0x0xb93226f4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:49:08 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.016 - - 0 Jan 29 10:49:10 sogod [4104]: <0x0xb94a98a4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:49:10 sogod [4104]: <0x0xb94a98a4[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:49:20 sogod [4104]: <0x0xb94a98a4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:49:20 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/193 10.506 - - 0 Jan 29 10:49:21 sogod [4104]: <0x0xb9560b64[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 Jan 29 10:49:21 sogod [4104]: <0x0xb9560b64[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:49:21 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/13 0.020 - - 0 Jan 29 10:49:21 sogod [4104]: <0x0xb94266ac[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 0 mail%2F5036bb35162fab56c80e0000b79f221a Email vevent%2Fpersonal Calendar vcard%2Fpersonal Contacts mail%2F981b3a01552fab56d60e0000b79f221a Email Jan 29 10:49:22 sogod [4104]: <0x0xb94266ac[SOGoActiveSyncDispatcher]> Sleeping 10 seconds while detecting changes in Ping... Jan 29 10:49:22 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/saveAsAppointment HTTP/1.1" 200 21/813 0.017 - - 0 Jan 29 10:49:22 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454060963 HTTP/1.1" 200 58/0 0.006 - - 0 Jan 29 10:49:22 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160130&filterpopup=view_today HTTP/1.1" 200 352/0 0.009 - - 0 Jan 29 10:49:22 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview?day=20160130 HTTP/1.1" 200 2273/0 0.014 54908 95% 0 Jan 29 10:49:22 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 713/0 0.010 - - 0 Jan 29 10:49:32 sogod [4104]: <0x0xb94266ac[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 5 10 Jan 29 10:49:32 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 19/192 10.504 - - 0 Jan 29 10:49:32 sogod [4104]: <0x0xb93d42ac[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 60 Jan 29 10:49:32 sogod [4104]: <0x0xb93d42ac[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 3 Jan 29 10:49:32 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Ping HTTP/1.1" 200 13/14 0.005 - - 0 Jan 29 10:49:32 sogod [4104]: <0x0xb952c0a4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454060786 vevent%2Fpersonal 5 4 1 2048 1 1 xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAEAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAEAAAAAAAAAxP///w== 20160129T094930Z 20160129T110000Z Test event 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 20160129T120000Z 1 0 2 Jan 29 10:49:32 sogod [4104]: <0x0xb952c0a4[SOGoActiveSyncDispatcher]> Cache cleanup needed for device IMEI357909041390858 - user: testuser syncKey: 1454060786 cache: 1454060918 Jan 29 10:49:32 sogod [4104]: <0x0xb952c0a4[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: CHANGE 1007-56AB3580-F-5DD63480 Jan 29 10:49:32 sogod [4104]: <0x0xb952c0a4[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: ADD 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 Jan 29 10:49:32 sogod [4104]: <0x0xb952c0a4[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 29 10:49:32 sogod [4104]: <0x0xb952c0a4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar 1454060972 vevent%2Fpersonal 1 1 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 1 1007-56AB3580-F-5DD63480 0 20160129T094922Z 20160130T120000Z 20160130T130000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 event test no defined 1007-56AB3580-F-5DD63480 0 15 1 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 0 20160129T110000Z 20160129T120000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 Test event 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 0 1 Jan 29 10:49:32 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 1166/546 0.070 - - 408K Jan 29 10:49:33 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566.ics/view HTTP/1.1" 200 282/0 0.019 - - 0 Jan 29 10:49:33 sogod [4104]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566.ics/edit HTTP/1.1" 200 10507/0 0.034 33169 68% 44K Jan 29 10:49:43 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/view HTTP/1.1" 200 292/0 0.014 - - 0 Jan 29 10:49:44 sogod [4104]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/edit HTTP/1.1" 200 10441/0 0.032 33026 68% 0 Jan 29 10:50:08 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566.ics/view HTTP/1.1" 200 282/0 0.012 - - 0 Jan 29 10:50:08 sogod [4104]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566.ics/edit HTTP/1.1" 200 10507/0 0.033 33169 68% 0 Jan 29 10:50:16 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/view HTTP/1.1" 200 292/0 0.011 - - 0 Jan 29 10:50:16 sogod [4104]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/edit HTTP/1.1" 200 10441/0 0.032 33026 68% 0 Jan 29 10:50:31 sogod [4104]: <0x0xb94bb99c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mond_XXX_local_e95_1c5b67eb_1 Jan 29 10:50:31 sogod [4104]: <0x0xb94bb99c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 mond_XXX_local_e95_1c5b67eb_1 0 Jan 29 10:50:31 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.336 - - 0 Jan 29 10:50:31 sogod [4104]: <0x0xb94b2ddc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mail%2F5036bb35162fab56c80e0000b79f221a 3 1-1 vevent%2Fpersonal 4 1454060786 vcard%2Fpersonal 0 1454059537 mail%2F981b3a01552fab56d60e0000b79f221a 3 3-2 Jan 29 10:50:32 sogod [4104]: <0x0xb95144f4[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 29 10:50:32 sogod [4104]: <0x0xb95144f4[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 29 10:50:32 sogod [4104]: <0x0xb94b2ddc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 Email mail%2F5036bb35162fab56c80e0000b79f221a 0 1 Calendar vevent%2Fpersonal 2 1 Contacts vcard%2Fpersonal 1 1 Email mail%2F981b3a01552fab56d60e0000b79f221a 1 Jan 29 10:50:32 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=GetItemEstimate HTTP/1.1" 200 233/221 0.658 - - 0 Jan 29 10:50:32 sogod [4104]: <0x0xb94ad3bc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454060786 vevent%2Fpersonal 5 4 1 2048 1 1 xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAEAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAEAAAAAAAAAxP///w== 20160129T095029Z 20160129T110000Z Test event 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 20160129T120000Z 1 0 2 Jan 29 10:50:32 sogod [4104]: <0x0xb94ad3bc[SOGoActiveSyncDispatcher]> Cache cleanup needed for device IMEI357909041390858 - user: testuser syncKey: 1454060786 cache: 1454060972 Jan 29 10:50:32 sogod [4104]: <0x0xb94ad3bc[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: ADD 1007-56AB3580-F-5DD63480 Jan 29 10:50:32 sogod [4104]: <0x0xb94ad3bc[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: ADD 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 Jan 29 10:50:32 sogod [4104]: <0x0xb94ad3bc[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 29 10:50:32 sogod [4104]: <0x0xb94ad3bc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar 1454061032 vevent%2Fpersonal 1 1 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 1 1007-56AB3580-F-5DD63480 0 20160129T094922Z 20160130T120000Z 20160130T130000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 event test no defined 1007-56AB3580-F-5DD63480 0 15 1 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 0 20160129T110000Z 20160129T120000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 Test event 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 0 1 Jan 29 10:50:32 sogod [4104]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 1166/546 0.053 - - 156K Jan 29 10:50:33 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/saveAsAppointment HTTP/1.1" 200 21/818 0.026 - - -52K Jan 29 10:50:33 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454061033 HTTP/1.1" 200 58/0 0.007 - - 8K Jan 29 10:50:33 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160130&filterpopup=view_today HTTP/1.1" 200 352/0 0.010 - - 36K Jan 29 10:50:33 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview?day=20160130 HTTP/1.1" 200 2273/0 0.020 54908 95% 28K Jan 29 10:50:33 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 713/0 0.008 - - 12K Jan 29 10:50:53 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/view HTTP/1.1" 200 292/0 0.015 - - 0 Jan 29 10:50:53 sogod [4104]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/edit HTTP/1.1" 200 10441/0 0.031 33026 68% 0 Jan 29 10:51:00 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/saveAsAppointment HTTP/1.1" 200 21/836 0.026 - - 0 Jan 29 10:51:00 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454061061 HTTP/1.1" 200 2/0 0.016 - - 0 Jan 29 10:51:00 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160130&filterpopup=view_today HTTP/1.1" 200 352/0 0.018 - - 0 Jan 29 10:51:00 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview?day=20160130 HTTP/1.1" 200 2273/0 0.024 54908 95% 0 Jan 29 10:51:00 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 704/0 0.007 - - 0 Jan 29 10:51:04 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/view HTTP/1.1" 200 292/0 0.014 - - 0 Jan 29 10:51:05 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/edit HTTP/1.1" 200 10434/0 0.036 32999 68% 4K Jan 29 10:51:11 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/saveAsAppointment HTTP/1.1" 200 21/821 0.027 - - 4K Jan 29 10:51:11 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454061071 HTTP/1.1" 200 2/0 0.020 - - 0 Jan 29 10:51:11 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160130&filterpopup=view_today HTTP/1.1" 200 352/0 0.019 - - 0 Jan 29 10:51:11 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview?day=20160130 HTTP/1.1" 200 2273/0 0.023 54908 95% 0 Jan 29 10:51:11 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 694/0 0.010 - - 0 Jan 29 10:51:16 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566.ics/view HTTP/1.1" 200 282/0 0.013 - - 0 Jan 29 10:51:18 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566.ics/view HTTP/1.1" 200 282/0 0.013 - - 0 Jan 29 10:51:19 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/view HTTP/1.1" 200 282/0 0.012 - - 0 Jan 29 10:51:21 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/view HTTP/1.1" 200 282/0 0.010 - - 0 Jan 29 10:51:22 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566.ics/view HTTP/1.1" 200 282/0 0.011 - - 0 Jan 29 10:52:39 sogod [4104]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566.ics/raw HTTP/1.1" 200 725/0 0.007 - - 0 Jan 29 10:52:44 sogod [4104]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/raw HTTP/1.1" 200 743/0 0.006 - - 0 Jan 29 10:55:36 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/view HTTP/1.1" 200 282/0 0.013 - - 0 Jan 29 10:55:37 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/view HTTP/1.1" 200 282/0 0.025 - - 0 Jan 29 10:55:38 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/view HTTP/1.1" 200 282/0 0.012 - - 0 Jan 29 10:55:38 sogod [4104]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/edit HTTP/1.1" 200 10430/0 0.032 32989 68% 0 Jan 29 10:55:47 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 10:55:47 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/taskslist?show-completed=0&asc=true&sort=end&filterpopup=view_today HTTP/1.1" 200 2/0 0.008 - - 0 Jan 29 10:55:47 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 10:55:47 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.008 - - 0 Jan 29 10:55:47 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454061347 HTTP/1.1" 200 2/0 0.028 - - 0 Jan 29 10:55:47 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160130&filterpopup=view_today HTTP/1.1" 200 352/0 0.026 - - 0 Jan 29 10:55:47 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview?day=20160130 HTTP/1.1" 200 2273/0 0.019 54908 95% 120K Jan 29 10:55:47 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 694/0 0.009 - - 0 Jan 29 10:56:12 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/1007-56AB3580-F-5DD63480.ics/saveAsAppointment HTTP/1.1" 200 21/821 0.023 - - 0 Jan 29 10:56:12 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454061372 HTTP/1.1" 200 2/0 0.004 - - 0 Jan 29 10:56:12 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview?day=20160130 HTTP/1.1" 200 2273/0 0.014 54908 95% -8K Jan 29 10:56:12 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160130&filterpopup=view_today HTTP/1.1" 200 352/0 0.007 - - 0 Jan 29 10:56:12 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 694/0 0.007 - - 0 Jan 29 10:56:26 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/acls HTTP/1.1" 200 3744/0 0.045 11058 66% 4K Jan 29 10:56:46 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454061406 HTTP/1.1" 200 2/0 0.012 - - 0 Jan 29 10:56:46 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160129&filterpopup=view_next7 HTTP/1.1" 200 614/0 0.012 - - 0 Jan 29 10:56:54 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454061414 HTTP/1.1" 200 2/0 0.015 - - 0 Jan 29 10:56:54 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160129&filterpopup=view_next7 HTTP/1.1" 200 614/0 0.013 - - 0 Jan 29 10:56:54 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/taskslist?show-completed=0&asc=true&sort=end&filterpopup=view_today HTTP/1.1" 200 2/0 0.006 - - 0 Jan 29 10:56:54 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.014 - - 0 Jan 29 10:56:54 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview?day=20160129 HTTP/1.1" 200 2273/0 0.021 54908 95% 0 Jan 29 10:56:54 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 694/0 0.010 - - 0 Jan 29 11:05:47 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454061947 HTTP/1.1" 200 2/0 0.013 - - 0 Jan 29 11:05:47 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 11:05:47 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/taskslist?show-completed=0&asc=true&sort=end&filterpopup=view_today HTTP/1.1" 200 2/0 0.010 - - 0 Jan 29 11:05:47 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.014 - - 4K Jan 29 11:05:47 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160129&filterpopup=view_next7 HTTP/1.1" 200 614/0 0.032 - - 0 Jan 29 11:05:47 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview?day=20160129 HTTP/1.1" 200 2273/0 0.020 54908 95% 0 Jan 29 11:05:47 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 694/0 0.017 - - 0 Jan 29 11:07:06 sogod [4103]: <0x0xb902ab3c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mond_XXX_local_e95_1c5b67eb_1 Jan 29 11:07:06 sogod [4103]: <0x0xb902ab3c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 mond_XXX_local_e95_1c5b67eb_1 0 Jan 29 11:07:06 sogod [4103]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.382 - - 368K Jan 29 11:07:07 sogod [4103]: <0x0xb938a904[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1-1 mail%2F5036bb35162fab56c80e0000b79f221a 5 3 2 2048 1 Jan 29 11:07:07 sogod [4103]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/98 0.425 - - 0 Jan 29 11:07:07 sogod [4103]: <0x0xb950b324[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454060786 vevent%2Fpersonal 5 0 1 2048 1 1 xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAEAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAEAAAAAAAAAxP///w== 20160129T095729Z 20160129T110000Z Test event 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 20160129T120000Z 1 0 2 Jan 29 11:07:07 sogod [4103]: <0x0xb950b324[SOGoActiveSyncDispatcher]> Cache cleanup needed for device IMEI357909041390858 - user: testuser syncKey: 1454060786 cache: 1454061032 Jan 29 11:07:07 sogod [4103]: <0x0xb950b324[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: ADD 1007-56AB3580-F-5DD63480 Jan 29 11:07:07 sogod [4103]: <0x0xb950b324[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: ADD 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 Jan 29 11:07:07 sogod [4103]: <0x0xb950b324[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 29 11:07:07 sogod [4103]: <0x0xb950b324[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar 1454062027 vevent%2Fpersonal 1 1 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 1 1007-56AB3580-F-5DD63480 0 20160129T094922Z 20160130T120000Z 20160130T130000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 event test 1007-56AB3580-F-5DD63480 0 1 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 0 20160129T110000Z 20160129T120000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 Test event 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 0 1 Jan 29 11:07:07 sogod [4103]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 1146/546 0.064 - - 516K Jan 29 11:07:20 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566.ics/saveAsAppointment HTTP/1.1" 200 21/821 0.022 - - 4K Jan 29 11:07:20 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454062041 HTTP/1.1" 200 2/0 0.003 - - 0 Jan 29 11:07:20 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview?day=20160129 HTTP/1.1" 200 2273/0 0.011 54908 95% 0 Jan 29 11:07:20 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160129&filterpopup=view_next7 HTTP/1.1" 200 614/0 0.007 - - 0 Jan 29 11:07:21 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 694/0 0.011 - - 0 Jan 29 11:07:25 sogod [4103]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts HTTP/1.1" 302 0/0 0.003 - - 8K Jan 29 11:07:25 sogod [4103]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts/view HTTP/1.1" 200 8010/0 0.053 25883 69% 48K Jan 29 11:07:26 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454062046 HTTP/1.1" 200 2/0 0.006 - - 0 Jan 29 11:07:26 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/groups/canAccessContent HTTP/1.1" 204 0/0 0.010 - - 4K Jan 29 11:07:26 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/canAccessContent HTTP/1.1" 204 0/0 0.013 - - 4K Jan 29 11:07:26 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/users/canAccessContent HTTP/1.1" 204 0/0 0.011 - - 8K Jan 29 11:07:32 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/view?noframe=1&search=name_or_address&value=.&sort=c_cn&asc=true HTTP/1.1" 200 237/0 0.008 - - 20K Jan 29 11:07:39 sogod [4104]: 192.168.10.181 "GET /SOGo/so/testuser/Mail HTTP/1.1" 302 0/0 0.004 - - 8K Jan 29 11:07:40 sogod [4104]: 192.168.10.181 "GET /SOGo/so/testuser/Mail/view HTTP/1.1" 200 11102/0 0.363 39138 71% 576K Jan 29 11:07:40 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454062060 HTTP/1.1" 200 2/0 0.006 - - 0 Jan 29 11:07:40 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/mailboxes HTTP/1.1" 200 341/0 0.227 - - 4K Jan 29 11:07:40 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/foldersState HTTP/1.1" 200 0/0 0.007 - - 0 Jan 29 11:07:41 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderINBOX/unseenCount HTTP/1.1" 200 13/0 0.280 - - 8K Jan 29 11:08:12 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderINBOX/uids HTTP/1.1" 200 28/53 0.353 - - 0 Jan 29 11:08:12 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderINBOX/unseenCount HTTP/1.1" 200 13/0 0.433 - - 12K Jan 29 11:08:12 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderINBOX/headers HTTP/1.1" 200 629/12 0.292 - - 72K Jan 29 11:08:43 sogod [4103]: <0x0xb957ce74[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mond_XXX_local_e95_1c5b67eb_1 Jan 29 11:08:44 sogod [4103]: <0x0xb957ce74[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 mond_XXX_local_e95_1c5b67eb_1 0 Jan 29 11:08:44 sogod [4103]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.341 - - 0 Jan 29 11:08:44 sogod [4103]: <0x0xb9518adc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mail%2F5036bb35162fab56c80e0000b79f221a 3 1-1 vevent%2Fpersonal 0 1454060786 vcard%2Fpersonal 0 1454059537 mail%2F981b3a01552fab56d60e0000b79f221a 3 3-2 Jan 29 11:08:44 sogod [4103]: <0x0xb94a3c3c[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 29 11:08:44 sogod [4103]: <0x0xb94a3c3c[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 29 11:08:44 sogod [4103]: <0x0xb9518adc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 Email mail%2F5036bb35162fab56c80e0000b79f221a 1 1 Calendar vevent%2Fpersonal 2 1 Contacts vcard%2Fpersonal 1 1 Email mail%2F981b3a01552fab56d60e0000b79f221a 1 Jan 29 11:08:44 sogod [4103]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=GetItemEstimate HTTP/1.1" 200 233/221 0.696 - - 0 Jan 29 11:08:45 sogod [4103]: <0x0xb941777c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1-1 mail%2F5036bb35162fab56c80e0000b79f221a 5 3 2 2048 1 Jan 29 11:08:45 sogod [4103]: <0x0xb95188dc[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 29 11:08:45 sogod [4103]: <0x0xb941777c[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (0) - lastObject: (null) Jan 29 11:08:45 sogod [4103]: <0x0xb941777c[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (1) - lastObject: 1-2 Jan 29 11:08:45 sogod [4103]: <0x0xb941777c[SOGoActiveSyncDispatcher]> EAS - found in cache: 0 k = 0 Jan 29 11:08:45 sogod [4103]: <0x0xb941777c[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 1-2 Jan 29 11:08:45 sogod [4103]: <0x0xb941777c[SOGoActiveSyncDispatcher]> EAS - ADD! Jan 29 11:08:45 sogod [4103]: <0x0xb941777c[SOGoActiveSyncDispatcher]> EAS - first uid added 1 Jan 29 11:08:45 sogod [4103]: <0x0xb941777c[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 29 11:08:45 sogod [4103]: <0x0xb941777c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Email 1-2 mail%2F5036bb35162fab56c80e0000b79f221a 1 1 "testuser@XXX.local" <testuser@XXX.local> "admin@XXX.local" <admin@XXX.local> test test 2016-01-29T10:08:05.000Z testuser 1 0 IPM.Note urn:content-classes:message 65001 1 0 test 22 0 1 Jan 29 11:08:45 sogod [4103]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 357/98 0.770 - - 92K Jan 29 11:08:46 sogod [4103]: <0x0xb94c8284[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454060786 vevent%2Fpersonal 5 0 1 2048 1 1 xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAEAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAEAAAAAAAAAxP///w== 20160129T095908Z 20160129T110000Z Test event 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 20160129T120000Z 1 0 2 Jan 29 11:08:46 sogod [4103]: <0x0xb94c8284[SOGoActiveSyncDispatcher]> Cache cleanup needed for device IMEI357909041390858 - user: testuser syncKey: 1454060786 cache: 1454062027 Jan 29 11:08:46 sogod [4103]: <0x0xb94c8284[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: ADD 1007-56AB3580-F-5DD63480 Jan 29 11:08:46 sogod [4103]: <0x0xb94c8284[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: ADD 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 Jan 29 11:08:46 sogod [4103]: <0x0xb94c8284[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 29 11:08:46 sogod [4103]: <0x0xb94c8284[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar 1454062126 vevent%2Fpersonal 1 1 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 1 1007-56AB3580-F-5DD63480 0 20160129T094922Z 20160130T120000Z 20160130T130000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 event test 1007-56AB3580-F-5DD63480 0 1 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 0 20160129T110000Z 20160129T120000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 Test event 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 0 1 Jan 29 11:08:46 sogod [4103]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 1146/546 0.054 - - 376K Jan 29 11:09:29 sogod [4103]: 192.168.10.181 "GET /SOGo/so/testuser/Mail/0/folderINBOX/expunge HTTP/1.1" 200 2/0 0.237 - - 0 Jan 29 11:09:29 sogod [4103]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts HTTP/1.1" 302 0/0 0.002 - - 0 Jan 29 11:09:29 sogod [4103]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts/view HTTP/1.1" 200 8010/0 0.022 25883 69% 0 Jan 29 11:09:29 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454062169 HTTP/1.1" 200 2/0 0.004 - - 0 Jan 29 11:09:29 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/canAccessContent HTTP/1.1" 204 0/0 0.008 - - 0 Jan 29 11:09:29 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/users/canAccessContent HTTP/1.1" 204 0/0 0.007 - - 0 Jan 29 11:09:29 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/groups/canAccessContent HTTP/1.1" 204 0/0 0.012 - - 0 Jan 29 11:09:32 sogod [4104]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar HTTP/1.1" 302 0/0 0.004 - - 0 Jan 29 11:09:32 sogod [4104]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/view HTTP/1.1" 200 14351/0 0.069 54063 73% 212K Jan 29 11:09:33 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/canAccessContent HTTP/1.1" 204 0/0 0.004 - - 0 Jan 29 11:09:33 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 11:09:33 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/taskslist?show-completed=0&asc=true&sort=end&filterpopup=view_today HTTP/1.1" 200 2/0 0.007 - - 0 Jan 29 11:09:33 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 11:09:33 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.003 - - 0 Jan 29 11:09:33 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454062173 HTTP/1.1" 200 2/0 0.015 - - 0 Jan 29 11:09:33 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=&filterpopup=view_next7 HTTP/1.1" 200 614/0 0.031 - - 0 Jan 29 11:09:33 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview HTTP/1.1" 200 2273/0 0.024 54908 95% 0 Jan 29 11:09:33 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 694/0 0.010 - - 0 Jan 29 11:09:46 sogod [4103]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts HTTP/1.1" 302 0/0 0.003 - - 0 Jan 29 11:09:46 sogod [4103]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts/view HTTP/1.1" 200 8010/0 0.021 25883 69% 0 Jan 29 11:09:46 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454062186 HTTP/1.1" 200 2/0 0.005 - - 0 Jan 29 11:09:46 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/users/canAccessContent HTTP/1.1" 204 0/0 0.004 - - 0 Jan 29 11:09:46 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/canAccessContent HTTP/1.1" 204 0/0 0.011 - - 0 Jan 29 11:09:46 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/groups/canAccessContent HTTP/1.1" 204 0/0 0.010 - - 0 Jan 29 11:10:43 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/view?noframe=1&search=name_or_address&value=.&sort=c_cn&asc=true HTTP/1.1" 200 237/0 0.008 - - 20K Jan 29 11:11:51 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/Mail HTTP/1.1" 302 0/0 0.003 - - 0 Jan 29 11:11:51 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/Mail/view HTTP/1.1" 200 11289/0 0.341 39638 71% 0 Jan 29 11:11:52 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454062312 HTTP/1.1" 200 2/0 0.004 - - 0 Jan 29 11:11:52 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/mailboxes HTTP/1.1" 200 341/0 0.225 - - 0 Jan 29 11:11:52 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/foldersState HTTP/1.1" 200 0/0 0.009 - - 0 Jan 29 11:11:52 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderINBOX/unseenCount HTTP/1.1" 200 13/0 0.281 - - 0 Jan 29 11:14:01 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/Mail/0/folderINBOX/expunge HTTP/1.1" 200 2/0 0.314 - - 0 Jan 29 11:14:01 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts HTTP/1.1" 302 0/0 0.002 - - 0 Jan 29 11:14:01 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts/view HTTP/1.1" 200 8010/0 0.084 25883 69% 64K Jan 29 11:14:02 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454062442 HTTP/1.1" 200 2/0 0.004 - - 0 Jan 29 11:14:02 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/canAccessContent HTTP/1.1" 204 0/0 0.008 - - 0 Jan 29 11:14:02 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/groups/canAccessContent HTTP/1.1" 204 0/0 0.009 - - 0 Jan 29 11:14:02 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/users/canAccessContent HTTP/1.1" 204 0/0 0.009 - - 0 Jan 29 11:14:04 sogod [4104]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar HTTP/1.1" 302 0/0 0.004 - - 0 Jan 29 11:14:04 sogod [4104]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/view HTTP/1.1" 200 14351/0 0.037 54063 73% 124K Jan 29 11:14:04 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/canAccessContent HTTP/1.1" 204 0/0 0.004 - - 0 Jan 29 11:14:04 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454062445 HTTP/1.1" 200 2/0 0.004 - - 0 Jan 29 11:14:04 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 11:14:04 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/taskslist?show-completed=0&asc=true&sort=end&filterpopup=view_today HTTP/1.1" 200 2/0 0.006 - - 0 Jan 29 11:14:04 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.008 - - 0 Jan 29 11:14:04 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=&filterpopup=view_next7 HTTP/1.1" 200 614/0 0.016 - - 0 Jan 29 11:14:04 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview HTTP/1.1" 200 2273/0 0.025 54908 95% 136K Jan 29 11:14:04 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 694/0 0.008 - - 0 Jan 29 11:14:14 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/Mail HTTP/1.1" 302 0/0 0.004 - - 0 Jan 29 11:14:15 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/Mail/view HTTP/1.1" 200 11289/0 0.347 39638 71% -20K Jan 29 11:14:15 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454062455 HTTP/1.1" 200 2/0 0.005 - - 0 Jan 29 11:14:15 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/mailboxes HTTP/1.1" 200 341/0 0.226 - - 0 Jan 29 11:14:15 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/foldersState HTTP/1.1" 200 0/0 0.008 - - 0 Jan 29 11:14:16 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderINBOX/unseenCount HTTP/1.1" 200 13/0 0.284 - - 0 Jan 29 11:14:18 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/Mail/0/folderINBOX/expunge HTTP/1.1" 200 2/0 0.238 - - 0 Jan 29 11:14:18 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar HTTP/1.1" 302 0/0 0.003 - - 0 Jan 29 11:14:18 sogod [4102]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/view HTTP/1.1" 200 14351/0 0.037 54063 73% -64K Jan 29 11:14:19 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/canAccessContent HTTP/1.1" 204 0/0 0.005 - - 0 Jan 29 11:14:19 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 11:14:19 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454062459 HTTP/1.1" 200 2/0 0.013 - - 0 Jan 29 11:14:19 sogod [4101]: [ERROR] <0x0xb9191094[WOWatchDog]> No child available to handle incoming request! Jan 29 11:14:19 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/taskslist?show-completed=0&asc=true&sort=end&filterpopup=view_today HTTP/1.1" 200 2/0 0.010 - - 0 Jan 29 11:14:19 sogod [4102]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.008 - - 0 Jan 29 11:14:19 sogod [4104]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=&filterpopup=view_next7 HTTP/1.1" 200 614/0 0.028 - - 0 Jan 29 11:14:19 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview HTTP/1.1" 200 2273/0 0.020 54908 95% 0 Jan 29 11:14:19 sogod [4103]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 694/0 0.007 - - 0 Jan 29 11:16:27 sogod [4101]: <0x0xb9191094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:16:27 sogod [4101]: <0x0xb91df14c[WOWatchDogChild]> sending terminate signal to pid 4104 Jan 29 11:16:27 sogod [4101]: <0x0xb91d6bc4[WOWatchDogChild]> sending terminate signal to pid 4103 Jan 29 11:16:27 sogod [4101]: <0x0xb901d554[WOWatchDogChild]> sending terminate signal to pid 4102 Jan 29 11:16:27 sogod [4101]: <0x0xb9191094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:16:27 sogod [4101]: <0x0xb9191094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:16:27 sogod [4101]: <0x0xb91df14c[WOWatchDogChild]> child 4104 exited Jan 29 11:16:27 sogod [4101]: <0x0xb9191094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:16:27 sogod [4101]: <0x0xb91d6bc4[WOWatchDogChild]> child 4103 exited Jan 29 11:16:27 sogod [4101]: <0x0xb9191094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:16:27 sogod [4101]: <0x0xb901d554[WOWatchDogChild]> child 4102 exited Jan 29 11:16:27 sogod [4101]: <0x0xb9191094[WOWatchDog]> all children exited. We now terminate. Jan 29 11:17:50 sogod [4966]: version 2.3.5 (build root@shiva.inverse 201601051534) -- starting Jan 29 11:17:50 sogod [4966]: vmem size check enabled: shutting down app when vmem > 384 MB Jan 29 11:17:50 sogod [4966]: <0x0xb979629c[SOGoProductLoader]> SOGo products loaded from '/usr/lib/GNUstep/SOGo': Jan 29 11:17:50 sogod [4966]: <0x0xb979629c[SOGoProductLoader]> CommonUI.SOGo, Appointments.SOGo, ActiveSync.SOGo, MainUI.SOGo, MailerUI.SOGo, AdministrationUI.SOGo, PreferencesUI.SOGo, Mailer.SOGo, Contacts.SOGo, SchedulerUI.SOGo, MailPartViewers.SOGo, ContactsUI.SOGo Jan 29 11:17:50 sogod [4966]: <0x0xb9864094[WOWatchDog]> listening on 127.0.0.1:20000 Jan 29 11:17:50 sogod [4966]: <0x0xb9864094[WOWatchDog]> watchdog process pid: 4966 Jan 29 11:17:50 sogod [4966]: <0x0xb74b38c0[WOWatchDogChild]> watchdog request timeout set to 10 minutes Jan 29 11:17:50 sogod [4966]: <0x0xb9864094[WOWatchDog]> preparing 5 children Jan 29 11:17:50 sogod [4966]: <0x0xb9864094[WOWatchDog]> child spawned with pid 4967 Jan 29 11:17:50 sogod [4966]: <0x0xb9864094[WOWatchDog]> child spawned with pid 4968 Jan 29 11:17:50 sogod [4966]: <0x0xb9864094[WOWatchDog]> child spawned with pid 4969 Jan 29 11:17:50 sogod [4966]: <0x0xb9864094[WOWatchDog]> child spawned with pid 4970 Jan 29 11:17:50 sogod [4966]: <0x0xb9864094[WOWatchDog]> child spawned with pid 4971 Jan 29 11:19:00 sogod [4966]: <0x0xb9864094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:19:00 sogod [4966]: <0x0xb98b214c[WOWatchDogChild]> sending terminate signal to pid 4971 Jan 29 11:19:00 sogod [4966]: <0x0xb98a9bc4[WOWatchDogChild]> sending terminate signal to pid 4970 Jan 29 11:19:00 sogod [4966]: <0x0xb96f0554[WOWatchDogChild]> sending terminate signal to pid 4969 Jan 29 11:19:00 sogod [4966]: <0x0xb9884e44[WOWatchDogChild]> sending terminate signal to pid 4968 Jan 29 11:19:00 sogod [4966]: <0x0xb987dd2c[WOWatchDogChild]> sending terminate signal to pid 4967 Jan 29 11:19:01 sogod [4966]: <0x0xb9864094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:19:01 sogod [4966]: <0x0xb9864094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:19:01 sogod [4966]: <0x0xb9864094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:19:02 sogod [4966]: <0x0xb9864094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:19:02 sogod [4966]: <0x0xb96f0554[WOWatchDogChild]> child 4969 exited Jan 29 11:19:02 sogod [4966]: <0x0xb98b214c[WOWatchDogChild]> child 4971 exited Jan 29 11:19:02 sogod [4966]: <0x0xb9864094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:19:02 sogod [4966]: <0x0xb987dd2c[WOWatchDogChild]> child 4967 exited Jan 29 11:19:02 sogod [4966]: <0x0xb9864094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:19:02 sogod [4966]: <0x0xb9864094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:19:02 sogod [4966]: <0x0xb9864094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:19:02 sogod [4966]: <0x0xb98a9bc4[WOWatchDogChild]> child 4970 exited Jan 29 11:19:02 sogod [4966]: <0x0xb9864094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:19:02 sogod [4966]: <0x0xb9884e44[WOWatchDogChild]> child 4968 exited Jan 29 11:19:02 sogod [4966]: <0x0xb9864094[WOWatchDog]> all children exited. We now terminate. Jan 29 11:20:46 sogod [1960]: version 2.3.5 (build root@shiva.inverse 201601051534) -- starting Jan 29 11:20:46 sogod [1960]: vmem size check enabled: shutting down app when vmem > 384 MB Jan 29 11:20:46 sogod [1960]: <0x0xb5333c34[SOGoProductLoader]> SOGo products loaded from '/usr/lib/GNUstep/SOGo': Jan 29 11:20:46 sogod [1960]: <0x0xb5333c34[SOGoProductLoader]> CommonUI.SOGo, Appointments.SOGo, ActiveSync.SOGo, MainUI.SOGo, MailerUI.SOGo, AdministrationUI.SOGo, PreferencesUI.SOGo, Mailer.SOGo, Contacts.SOGo, SchedulerUI.SOGo, MailPartViewers.SOGo, ContactsUI.SOGo Jan 29 11:20:48 sogod [1960]: <0x0xb50b3a04[WOWatchDog]> listening on 127.0.0.1:20000 Jan 29 11:20:48 sogod [1960]: <0x0xb50b3a04[WOWatchDog]> watchdog process pid: 1960 Jan 29 11:20:48 sogod [1960]: <0x0x6018c0[WOWatchDogChild]> watchdog request timeout set to 10 minutes Jan 29 11:20:48 sogod [1960]: <0x0xb50b3a04[WOWatchDog]> preparing 5 children Jan 29 11:20:48 sogod [1960]: <0x0xb50b3a04[WOWatchDog]> child spawned with pid 1984 Jan 29 11:20:48 sogod [1960]: <0x0xb50b3a04[WOWatchDog]> child spawned with pid 1985 Jan 29 11:20:48 sogod [1960]: <0x0xb50b3a04[WOWatchDog]> child spawned with pid 1987 Jan 29 11:20:48 sogod [1960]: <0x0xb50b3a04[WOWatchDog]> child spawned with pid 1988 Jan 29 11:20:48 sogod [1960]: <0x0xb50b3a04[WOWatchDog]> child spawned with pid 1989 Jan 29 11:23:31 sogod [1960]: [ERROR] <0x0xb50b3a04[WOWatchDog]> No child available to handle incoming request! Jan 29 11:23:31 sogod [1988]: <0x0xb53440bc[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 29 11:23:31 sogod [1988]: <0x0xb53440bc[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 29 11:23:31 sogod [1984]: <0x0xb53440bc[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 29 11:23:31 sogod [1984]: <0x0xb53440bc[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 29 11:23:31 sogod [1989]: <0x0xb53440bc[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 29 11:23:31 sogod [1989]: <0x0xb53440bc[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 29 11:23:31 sogod [1987]: <0x0xb53cc8ac[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 29 11:23:31 sogod [1987]: <0x0xb53cc8ac[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 29 11:23:31 sogod [1985]: <0x0xb53cc8ac[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 29 11:23:31 sogod [1985]: <0x0xb53cc8ac[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 29 11:23:32 sogod [1985]: [WARN] <0x0xb4bcfd9c[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 29 11:23:32 sogod [1984]: [WARN] <0x0xb4beaddc[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions tableJan 29 11:23:32 sogod [1985]: [WARN] <0x0xb4bcfd9c[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 29 11:23:32 sogod [1985]: [WARN] <0x0xb4bcfd9c[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 29 11:23:32 sogod [1989]: [WARN] <0x0xb4beada4[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 29 11:23:32 sogod [1989]: [WARN] <0x0xb4beada4[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 29 11:23:32 sogod [1989]: [WARN] <0x0xb4beada4[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 29 11:23:32 sogod [1987]: [WARN] <0x0xb4beaae4[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 29 11:23:32 sogod [1987]: [WARN] <0x0xb4beaae4[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 29 11:23:32 sogod [1987]: [WARN] <0x0xb4beaae4[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 29 11:23:32 sogod [1988]: [WARN] <0x0xb4beab3c[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 29 11:23:32 sogod [1988]: [WARN] <0x0xb4beab3c[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 29 11:23:32 sogod [1988]: [WARN] <0x0xb4beab3c[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 29 11:23:32 sogod [1984]: [WARN] <0x0xb4beaddc[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 29 11:23:32 sogod [1984]: [WARN] <0x0xb4beaddc[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 29 11:23:32 sogod [1988]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/canAccessContent HTTP/1.1" 204 0/0 1.031 - - 2M Jan 29 11:23:32 sogod [1987]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.869 - - 2M Jan 29 11:23:32 sogod [1988]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454063011 HTTP/1.1" 200 2/0 0.039 - - 88KJan 29 11:23:32 sogod [1984]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/taskslist?show-completed=0&asc=true&sort=end&filterpopup=view_today HTTP/1.1" 200 2/0 0.912 - - 2M Jan 29 11:23:32 sogod [1989]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=&filterpopup=view_next7 HTTP/1.1" 200 614/0 1.004 - - 2M Jan 29 11:23:32 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview HTTP/1.1" 200 2273/0 1.235 54908 95% 3M Jan 29 11:23:32 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 694/0 0.013 - - 148K Jan 29 11:23:38 sogod [1985]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts HTTP/1.1" 302 0/0 0.003 - - 20K Jan 29 11:23:38 sogod [1985]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts/view HTTP/1.1" 200 8010/0 0.215 25883 69% 576K Jan 29 11:23:38 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454063019 HTTP/1.1" 200 2/0 0.006 - - 0 Jan 29 11:23:38 sogod [1989]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/groups/canAccessContent HTTP/1.1" 204 0/0 0.011 - - 56K Jan 29 11:23:38 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/canAccessContent HTTP/1.1" 204 0/0 0.016 - - 16K Jan 29 11:23:38 sogod [1988]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/users/canAccessContent HTTP/1.1" 204 0/0 0.013 - - 52K Jan 29 11:23:48 sogod [1988]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts/personal/newcontact HTTP/1.1" 302 0/0 0.058 - - 1M Jan 29 11:23:48 sogod [1988]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts/personal//7C4-56AB3D80-1-7B2EC380.vcf/editAsContact HTTP/1.1" 200 5722/0 0.063 21916 73% 988K Jan 29 11:23:59 sogod [1988]: <0x0xb4c185ac[WOTextField]> -[WOInput takeValuesFromRequest:inContext:]: form value is not settable: <0x0xb4c18724[WOValueAssociation]: value=""> Jan 29 11:23:59 sogod [1988]: [WARN] UIxDatePicker Could not parse dateString: '0000-00-00' Jan 29 11:23:59 sogod [1988]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/7C4-56AB3D80-1-7B2EC380.vcf/saveAsContact HTTP/1.1" 200 658/728 0.038 - - 44K Jan 29 11:23:59 sogod [1988]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/view?noframe=1&search=name_or_address&value=.&sort=c_cn&asc=true HTTP/1.1" 200 237/0 0.019 - - 12K Jan 29 11:23:59 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/7C4-56AB3D80-1-7B2EC380.vcf/view?noframe=1 HTTP/1.1" 200 393/0 0.039 - - 116K Jan 29 11:24:04 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/view?noframe=1&search=name_or_address&value=.&sort=c_cn&asc=true HTTP/1.1" 200 237/0 0.005 - - 4K Jan 29 11:24:17 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/groups/view?noframe=1&search=name_or_address&value=.&sort=c_cn&asc=true HTTP/1.1" 200 426/0 0.010 - - 4K Jan 29 11:24:18 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/view?noframe=1&search=name_or_address&value=.&sort=c_cn&asc=true HTTP/1.1" 200 237/0 0.006 - - 0 Jan 29 11:24:24 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/users/view?noframe=1&search=name_or_address&value=.&sort=c_cn&asc=true HTTP/1.1" 200 962/0 0.010 - - 0 Jan 29 11:24:34 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/groups/view?noframe=1&search=name_or_address&value=.&sort=c_cn&asc=true HTTP/1.1" 200 426/0 0.009 - - 0 Jan 29 11:24:35 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/view?noframe=1&search=name_or_address&value=.&sort=c_cn&asc=true HTTP/1.1" 200 237/0 0.006 - - 0 Jan 29 11:24:40 sogod [1985]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts/personal/newcontact HTTP/1.1" 302 0/0 0.018 - - 20K Jan 29 11:24:40 sogod [1985]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts/personal//7C1-56AB3E00-1-1B4A7920.vcf/editAsContact HTTP/1.1" 200 5723/0 0.021 21916 73% 56K Jan 29 11:24:53 sogod [1985]: <0x0xb4bc06e4[WOTextField]> -[WOInput takeValuesFromRequest:inContext:]: form value is not settable: <0x0xb4b98a64[WOValueAssociation]: value=""> Jan 29 11:24:53 sogod [1985]: [WARN] UIxDatePicker Could not parse dateString: '0000-00-00' Jan 29 11:24:53 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/7C1-56AB3E00-1-1B4A7920.vcf/saveAsContact HTTP/1.1" 200 658/730 0.014 - - 60K Jan 29 11:24:53 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/view?noframe=1&search=name_or_address&value=.&sort=c_cn&asc=true HTTP/1.1" 200 237/0 0.008 - - 0 Jan 29 11:24:53 sogod [1988]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/7C1-56AB3E00-1-1B4A7920.vcf/view?noframe=1 HTTP/1.1" 200 395/0 0.017 - - 56K Jan 29 11:25:35 sogod [1988]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/view?noframe=1&search=name_or_address&value=.&sort=c_cn&asc=true HTTP/1.1" 200 237/0 0.007 - - 0 Jan 29 11:25:38 sogod [1988]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/view?noframe=1&search=name_or_address&value=.&sort=c_cn&asc=true HTTP/1.1" 200 237/0 0.008 - - 0 Jan 29 11:25:39 sogod [1988]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/view?noframe=1&search=name_or_address&value=.&sort=c_cn&asc=true HTTP/1.1" 200 237/0 0.005 - - 0 Jan 29 11:25:57 sogod [1988]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/E95-56AB3000-1-16AE9120.vcf/view?noframe=1 HTTP/1.1" 200 723/0 0.005 - - 12K Jan 29 11:26:06 sogod [1988]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts/personal/newcontact HTTP/1.1" 302 0/0 0.004 - - 0 Jan 29 11:26:06 sogod [1988]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts/personal//7C4-56AB3E00-3-7B2EC380.vcf/editAsContact HTTP/1.1" 200 5723/0 0.016 21916 73% 36K Jan 29 11:26:28 sogod [1988]: <0x0xb4c185ac[WOTextField]> -[WOInput takeValuesFromRequest:inContext:]: form value is not settable: <0x0xb4c18724[WOValueAssociation]: value=""> Jan 29 11:26:28 sogod [1988]: [WARN] UIxDatePicker Could not parse dateString: '0000-00-00' Jan 29 11:26:28 sogod [1988]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/7C4-56AB3E00-3-7B2EC380.vcf/saveAsContact HTTP/1.1" 200 658/749 0.011 - - 0 Jan 29 11:26:28 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/7C4-56AB3E00-3-7B2EC380.vcf/view?noframe=1 HTTP/1.1" 200 544/0 0.007 - - 0 Jan 29 11:26:28 sogod [1988]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/view?noframe=1&search=name_or_address&value=.&sort=c_cn&asc=true HTTP/1.1" 200 477/0 0.013 - - 0 Jan 29 11:27:00 sogod [1988]: <0x0xb4c1b414[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mond_XXX_local_e95_1c5b67eb_1 Jan 29 11:27:00 sogod [1988]: <0x0xb4c1b414[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 mond_XXX_local_e95_1c5b67eb_1 0 Jan 29 11:27:00 sogod [1988]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.643 - - 524K Jan 29 11:27:01 sogod [1988]: <0x0xb4b995ac[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mail%2F5036bb35162fab56c80e0000b79f221a 3 1-2 vevent%2Fpersonal 0 1454060786 vcard%2Fpersonal 0 1454059537 mail%2F981b3a01552fab56d60e0000b79f221a 3 3-2 Jan 29 11:27:01 sogod [1988]: <0x0xb4ba884c[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 29 11:27:01 sogod [1988]: <0x0xb4ba884c[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 29 11:27:01 sogod [1988]: <0x0xb4b995ac[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 Email mail%2F5036bb35162fab56c80e0000b79f221a 1 1 Calendar vevent%2Fpersonal 2 1 Contacts vcard%2Fpersonal 4 1 Email mail%2F981b3a01552fab56d60e0000b79f221a 1 Jan 29 11:27:01 sogod [1988]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=GetItemEstimate HTTP/1.1" 200 233/221 0.789 - - 88K Jan 29 11:27:02 sogod [1988]: <0x0xb4b70414[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1-2 mail%2F5036bb35162fab56c80e0000b79f221a 5 3 2 2048 1 1 1 Jan 29 11:27:02 sogod [1988]: <0x0xb4b91b1c[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 29 11:27:02 sogod [1988]: <0x0xb4b70414[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (1) - lastObject: 1-3 Jan 29 11:27:02 sogod [1988]: <0x0xb4b70414[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (1) - lastObject: 1-3 Jan 29 11:27:02 sogod [1988]: <0x0xb4b70414[SOGoActiveSyncDispatcher]> EAS - found in cache: 0 k = 0 Jan 29 11:27:02 sogod [1988]: <0x0xb4b70414[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 1-3 Jan 29 11:27:02 sogod [1988]: <0x0xb4b70414[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 29 11:27:02 sogod [1988]: <0x0xb4b70414[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Email 2-3 mail%2F5036bb35162fab56c80e0000b79f221a 1 1 1 Jan 29 11:27:02 sogod [1988]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 88/117 0.884 - - 68K Jan 29 11:27:03 sogod [1988]: <0x0xb4d599b4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454060786 vevent%2Fpersonal 5 0 1 2048 1 1 xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAEAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAEAAAAAAAAAxP///w== 20160129T101725Z 20160129T110000Z Test event 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 20160129T120000Z 1 0 2 Jan 29 11:27:03 sogod [1988]: <0x0xb4d599b4[SOGoActiveSyncDispatcher]> Cache cleanup needed for device IMEI357909041390858 - user: testuser syncKey: 1454060786 cache: 1454062126 Jan 29 11:27:03 sogod [1988]: <0x0xb4d599b4[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: ADD 1007-56AB3580-F-5DD63480 Jan 29 11:27:03 sogod [1988]: <0x0xb4d599b4[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: ADD 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 Jan 29 11:27:03 sogod [1988]: <0x0xb4d599b4[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 29 11:27:03 sogod [1988]: <0x0xb4d599b4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar 1454063223 vevent%2Fpersonal 1 1 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 1 1007-56AB3580-F-5DD63480 0 20160129T094922Z 20160130T120000Z 20160130T130000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 event test 1007-56AB3580-F-5DD63480 0 1 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 0 20160129T110000Z 20160129T120000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 Test event 4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566 0 1 Jan 29 11:27:03 sogod [1988]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 1146/546 0.130 - - 1M Jan 29 11:29:37 sogod [1988]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/view?noframe=1&search=name_or_address&value=.&sort=c_cn&asc=true HTTP/1.1" 200 477/0 0.006 - - 0 Jan 29 11:29:40 sogod [1988]: 192.168.10.181 "GET /SOGo/so/testuser/Mail HTTP/1.1" 302 0/0 0.004 - - 8K Jan 29 11:29:40 sogod [1988]: 192.168.10.181 "GET /SOGo/so/testuser/Mail/view HTTP/1.1" 200 11282/0 0.499 39621 71% 776K Jan 29 11:29:40 sogod [1988]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454063381 HTTP/1.1" 200 2/0 0.005 - - 0 Jan 29 11:29:41 sogod [1988]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/mailboxes HTTP/1.1" 200 341/0 0.227 - - 4K Jan 29 11:29:41 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/foldersState HTTP/1.1" 200 0/0 0.033 - - 40K Jan 29 11:29:41 sogod [1988]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderINBOX/unseenCount HTTP/1.1" 200 13/0 0.292 - - 8K Jan 29 11:29:43 sogod [1988]: 192.168.10.181 "GET /SOGo/so/testuser/Mail/0/folderINBOX/expunge HTTP/1.1" 200 2/0 0.238 - - 0 Jan 29 11:29:43 sogod [1988]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar HTTP/1.1" 302 0/0 0.003 - - 0 Jan 29 11:29:43 sogod [1988]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/view HTTP/1.1" 200 14351/0 0.109 54063 73% 196K Jan 29 11:29:44 sogod [1988]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/canAccessContent HTTP/1.1" 204 0/0 0.003 - - 0 Jan 29 11:29:44 sogod [1988]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454063384 HTTP/1.1" 200 2/0 0.025 - - 0 Jan 29 11:29:44 sogod [1987]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.012 - - 0 Jan 29 11:29:44 sogod [1989]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/taskslist?show-completed=0&asc=true&sort=end&filterpopup=view_today HTTP/1.1" 200 2/0 0.031 - - 8K Jan 29 11:29:44 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=&filterpopup=view_next7 HTTP/1.1" 200 614/0 0.041 - - 4K Jan 29 11:29:44 sogod [1984]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview HTTP/1.1" 200 2273/0 0.099 54908 95% 1M Jan 29 11:29:44 sogod [1984]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 694/0 0.008 - - 20K Jan 29 11:30:15 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview?day=20160201 HTTP/1.1" 200 2260/0 0.019 54875 95% 100K Jan 29 11:30:15 sogod [1984]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/dateselector?day=20160201 HTTP/1.1" 200 744/0 0.026 9373 92% 60K Jan 29 11:30:15 sogod [1984]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160201&ed=20160207&view=weekview HTTP/1.1" 200 64/0 0.004 - - 0 Jan 29 11:30:23 sogod [1984]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/saveSelectedList HTTP/1.1" 204 0/24 0.017 - - 72K Jan 29 11:30:24 sogod [1984]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/saveSelectedList HTTP/1.1" 204 0/25 0.005 - - 0 Jan 29 11:30:29 sogod [1984]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/dateselector?day=20160125 HTTP/1.1" 200 765/0 0.019 9375 91% 100K Jan 29 11:30:29 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/weekview?day=20160125 HTTP/1.1" 200 2273/0 0.023 54908 95% 0 Jan 29 11:30:29 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160125&ed=20160131&view=weekview HTTP/1.1" 200 694/0 0.010 - - 0 Jan 29 11:30:33 sogod [1985]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts HTTP/1.1" 302 0/0 0.003 - - 0 Jan 29 11:30:33 sogod [1985]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts/view HTTP/1.1" 200 8123/0 0.024 26710 69% 0 Jan 29 11:30:33 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454063433 HTTP/1.1" 200 2/0 0.005 - - 0 Jan 29 11:30:33 sogod [1989]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/users/canAccessContent HTTP/1.1" 204 0/0 0.003 - - 0 Jan 29 11:30:33 sogod [1984]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/groups/canAccessContent HTTP/1.1" 204 0/0 0.012 - - 56K Jan 29 11:30:33 sogod [1985]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/canAccessContent HTTP/1.1" 204 0/0 0.015 - - 0 Jan 29 11:37:24 sogod [1960]: <0x0xb50b3a04[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:37:24 sogod [1960]: <0x0xb50f9534[WOWatchDogChild]> sending terminate signal to pid 1989 Jan 29 11:37:24 sogod [1960]: <0x0xb50d47b4[WOWatchDogChild]> sending terminate signal to pid 1988 Jan 29 11:37:24 sogod [1960]: <0x0xb50cd69c[WOWatchDogChild]> sending terminate signal to pid 1987 Jan 29 11:37:24 sogod [1960]: <0x0xb50ca224[WOWatchDogChild]> sending terminate signal to pid 1985 Jan 29 11:37:24 sogod [1960]: <0x0xb50bec6c[WOWatchDogChild]> sending terminate signal to pid 1984 Jan 29 11:37:24 sogod [1960]: <0x0xb50b3a04[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:37:24 sogod [1960]: <0x0xb50b3a04[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:37:24 sogod [1960]: <0x0xb50b3a04[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:37:24 sogod [1960]: <0x0xb50bec6c[WOWatchDogChild]> child 1984 exited Jan 29 11:37:24 sogod [1960]: <0x0xb50b3a04[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:37:24 sogod [1960]: <0x0xb50d47b4[WOWatchDogChild]> child 1988 exited Jan 29 11:37:24 sogod [1960]: <0x0xb50b3a04[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:37:24 sogod [1960]: <0x0xb50ca224[WOWatchDogChild]> child 1985 exited Jan 29 11:37:24 sogod [1960]: <0x0xb50b3a04[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:37:24 sogod [1960]: <0x0xb50cd69c[WOWatchDogChild]> child 1987 exited Jan 29 11:37:25 sogod [1960]: <0x0xb50b3a04[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 29 11:37:25 sogod [1960]: <0x0xb50f9534[WOWatchDogChild]> child 1989 exited Jan 29 11:37:25 sogod [1960]: <0x0xb50b3a04[WOWatchDog]> all children exited. We now terminate.