Jan 30 16:14:54 sogod [1960]: version 2.3.5 (build root@shiva.inverse 201601051534) -- starting Jan 30 16:14:54 sogod [1960]: vmem size check enabled: shutting down app when vmem > 384 MB Jan 30 16:14:55 sogod [1960]: <0x0x2cf9c0c[SOGoProductLoader]> SOGo products loaded from '/usr/lib/GNUstep/SOGo': Jan 30 16:14:55 sogod [1960]: <0x0x2cf9c0c[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 30 16:14:56 sogod [1960]: <0x0x2dc79cc[WOWatchDog]> listening on 127.0.0.1:20000 Jan 30 16:14:56 sogod [1960]: <0x0x2dc79cc[WOWatchDog]> watchdog process pid: 1960 Jan 30 16:14:56 sogod [1960]: <0x0xa518c0[WOWatchDogChild]> watchdog request timeout set to 10 minutes Jan 30 16:14:56 sogod [1960]: <0x0x2dc79cc[WOWatchDog]> preparing 5 children Jan 30 16:14:56 sogod [1960]: <0x0x2dc79cc[WOWatchDog]> child spawned with pid 1971 Jan 30 16:14:56 sogod [1960]: <0x0x2dc79cc[WOWatchDog]> child spawned with pid 1973 Jan 30 16:14:56 sogod [1960]: <0x0x2dc79cc[WOWatchDog]> child spawned with pid 1976 Jan 30 16:14:56 sogod [1960]: <0x0x2dc79cc[WOWatchDog]> child spawned with pid 1977 Jan 30 16:14:56 sogod [1960]: <0x0x2dc79cc[WOWatchDog]> child spawned with pid 1978 Jan 30 16:17:23 sogod [1977]: <0x0x2d012dc[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 30 16:17:23 sogod [1977]: <0x0x2d012dc[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 30 16:17:23 sogod [1977]: 192.168.10.181 "GET /SOGo HTTP/1.1" 302 0/0 0.301 - - 2M Jan 30 16:17:23 sogod [1977]: 192.168.10.181 "GET /SOGo/ HTTP/1.1" 200 4629/0 0.134 13451 65% 1M Jan 30 16:17:30 sogod [1977]: SOGoRootPage successful login from '192.168.10.181' for user 'testuser' - expire = -1 grace = -1 Jan 30 16:17:30 sogod [1977]: [WARN] <0x0xb629beac[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 30 16:17:30 sogod [1977]: [WARN] <0x0xb629beac[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 30 16:17:30 sogod [1977]: [WARN] <0x0xb629beac[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 30 16:17:30 sogod [1977]: 192.168.10.181 "POST /SOGo/connect HTTP/1.1" 200 27/43 0.357 - - 640K Jan 30 16:17:30 sogod [1977]: 192.168.10.181 "GET /SOGo/testuser HTTP/1.1" 302 0/0 0.004 - - 12K Jan 30 16:17:30 sogod [1977]: 192.168.10.181 "GET /SOGo/testuser/view HTTP/1.1" 302 0/0 0.021 - - 8K Jan 30 16:17:30 sogod [1977]: 192.168.10.181 "GET /SOGo/so/testuser/Mail HTTP/1.1" 302 0/0 0.004 - - 20K Jan 30 16:17:31 sogod [1977]: 192.168.10.181 "GET /SOGo/so/testuser/Mail/view HTTP/1.1" 200 11324/0 0.759 39676 71% 1M Jan 30 16:17:31 sogod [1977]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454167052 HTTP/1.1" 200 57/0 0.058 - - 112K Jan 30 16:17:31 sogod [1977]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/mailboxes HTTP/1.1" 200 341/0 0.225 - - 8K Jan 30 16:17:32 sogod [1973]: <0x0xb653109c[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 30 16:17:32 sogod [1973]: <0x0xb653109c[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 30 16:17:32 sogod [1973]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/foldersState HTTP/1.1" 200 0/0 0.153 - - 2M Jan 30 16:17:32 sogod [1977]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderINBOX/unseenCount HTTP/1.1" 200 13/0 0.319 - - 8K Jan 30 16:18:39 sogod [1977]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderSent/unseenCount HTTP/1.1" 200 13/0 0.337 - - 0 Jan 30 16:18:39 sogod [1973]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderSent/uids HTTP/1.1" 200 438/75 0.408 1139 61% 692K Jan 30 16:18:42 sogod [1973]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderSent/3/view?noframe=1 HTTP/1.1" 200 600/0 0.395 1856 67% 420K Jan 30 16:18:44 sogod [1973]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderSent/1/view?noframe=1 HTTP/1.1" 200 594/0 0.332 1824 67% 32K Jan 30 16:18:52 sogod [1973]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderjunkmail/uids HTTP/1.1" 200 170/75 0.355 - - 0 Jan 30 16:18:52 sogod [1977]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderjunkmail/unseenCount HTTP/1.1" 200 13/0 0.434 - - 0 Jan 30 16:18:53 sogod [1973]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderTrash/unseenCount HTTP/1.1" 200 13/0 0.308 - - 16K Jan 30 16:18:53 sogod [1977]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderTrash/uids HTTP/1.1" 200 420/75 0.388 1131 62% 0 Jan 30 16:18:59 sogod [1973]: [ERROR] <0x0xb63b541c[NGImap4Connection]> could not select URL: imap://testuser@localhost/Drafts/: {RawResponse = "{ResponseResult = {description = \"Mailbox doesn't exist: Drafts\"; result = no; tagId = 6; }; }"; reason = "Mailbox doesn't exist: Drafts"; result = 0; } Jan 30 16:18:59 sogod [1973]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderDrafts/unseenCount HTTP/1.1" 200 13/0 0.260 - - 20K Jan 30 16:18:59 sogod [1977]: [ERROR] <0x0xb63e735c[NGImap4Connection]> could not select URL: imap://testuser@localhost/Drafts/: {RawResponse = "{ResponseResult = {description = \"Mailbox doesn't exist: Drafts\"; result = no; tagId = 6; }; }"; reason = "Mailbox doesn't exist: Drafts"; result = 0; } Jan 30 16:18:59 sogod [1977]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderDrafts/uids HTTP/1.1" 200 158/75 0.291 - - 16K Jan 30 16:18:59 sogod [1977]: [ERROR] <0x0x2e1e96c[NGImap4Connection]> could not select URL: imap://testuser@localhost/Drafts/: {RawResponse = "{ResponseResult = {description = \"Mailbox doesn't exist: Drafts\"; result = no; tagId = 6; }; }"; reason = "Mailbox doesn't exist: Drafts"; result = 0; } Jan 30 16:18:59 sogod [1977]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderDrafts/headers HTTP/1.1" 200 130/98 0.227 - - 0 Jan 30 16:19:00 sogod [1977]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderINBOX/uids HTTP/1.1" 200 28/92 0.285 - - 0 Jan 30 16:19:38 sogod [1977]: 192.168.10.181 "GET /SOGo/so/testuser/Mail/0/folderINBOX/expunge HTTP/1.1" 200 2/0 0.242 - - 4K Jan 30 16:19:38 sogod [1977]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts HTTP/1.1" 302 0/0 0.003 - - 12K Jan 30 16:19:39 sogod [1977]: 192.168.10.181 "GET /SOGo/so/testuser/Contacts/view HTTP/1.1" 200 21843/0 0.258 114210 80% 144K Jan 30 16:19:39 sogod [1977]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454167180 HTTP/1.1" 200 57/0 0.008 - - 0 Jan 30 16:19:39 sogod [1973]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/groups/canAccessContent HTTP/1.1" 204 0/0 0.020 - - 84K Jan 30 16:19:39 sogod [1977]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/canAccessContent HTTP/1.1" 204 0/0 0.008 - - 16K Jan 30 16:19:39 sogod [1971]: <0x0xb653109c[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 30 16:19:39 sogod [1971]: <0x0xb653109c[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 30 16:19:39 sogod [1971]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/users/canAccessContent HTTP/1.1" 204 0/0 0.059 - - 900K Jan 30 16:19:42 sogod [1971]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/A83-56AC9C00-409-35EC8C00/view?noframe=1 HTTP/1.1" 200 712/0 0.081 - - 1M Jan 30 16:19:47 sogod [1971]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/batchDelete HTTP/1.1" 204 0/37 0.008 - - 32K Jan 30 16:19:47 sogod [1971]: 192.168.10.181 "POST /SOGo/so/testuser/Contacts/personal/A83-56AC9C00-405-35EC8C00/view?noframe=1 HTTP/1.1" 200 548/0 0.007 - - 12K Jan 30 16:20:06 sogod [1971]: 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 8426/0 0.123 46501 81% 1M Jan 30 16:20:12 sogod [1971]: [WARN] <0x0xb63b8944[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 30 16:20:12 sogod [1971]: [WARN] <0x0xb63b8944[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 30 16:20:12 sogod [1971]: [WARN] <0x0xb63b8944[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 30 16:20:12 sogod [1971]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar HTTP/1.1" 302 0/0 0.008 - - 68K Jan 30 16:20:12 sogod [1971]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/view HTTP/1.1" 200 14436/0 0.216 54249 73% 952K Jan 30 16:20:13 sogod [1971]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/canAccessContent HTTP/1.1" 204 0/0 0.004 - - 4K Jan 30 16:20:13 sogod [1973]: [WARN] <0x0xb6455574[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions tableJan 30 16:20:13 sogod [1977]: 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.029 - - 44K Jan 30 16:20:13 sogod [1973]: [WARN] <0x0xb6455574[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 30 16:20:13 sogod [1973]: [WARN] <0x0xb6455574[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 30 16:20:13 sogod [1971]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454167214 HTTP/1.1" 200 57/0 0.039 - - 8K Jan 30 16:20:13 sogod [1973]: 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.060 - - 180K Jan 30 16:20:13 sogod [1978]: <0x0x2d5c4cc[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 30 16:20:13 sogod [1978]: <0x0x2d5c4cc[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 30 16:20:13 sogod [1976]: <0x0x2ce627c[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 30 16:20:13 sogod [1976]: <0x0x2ce627c[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 30 16:20:13 sogod [1978]: [WARN] <0x0xb63820dc[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 30 16:20:13 sogod [1978]: [WARN] <0x0xb63820dc[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 30 16:20:13 sogod [1978]: [WARN] <0x0xb63820dc[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions tableJan 30 16:20:13 sogod [1976]: [WARN] <0x0xb63820c4[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 30 16:20:13 sogod [1976]: [WARN] <0x0xb63820c4[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 30 16:20:13 sogod [1976]: [WARN] <0x0xb63820c4[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 30 16:20:13 sogod [1976]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.151 - - 1M Jan 30 16:20:13 sogod [1978]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/monthview HTTP/1.1" 200 1257/0 0.233 6537 80% 2M Jan 30 16:20:13 sogod [1978]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20151228&ed=20160131&view=monthview HTTP/1.1" 200 564/0 0.012 - - 188K Jan 30 16:20:23 sogod [1978]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/dateselector?day=20160201 HTTP/1.1" 200 744/0 0.028 9373 92% 128K Jan 30 16:20:23 sogod [1976]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/monthview?day=20160201 HTTP/1.1" 200 1211/0 0.073 6560 81% 1M Jan 30 16:20:23 sogod [1976]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160201&ed=20160306&view=monthview HTTP/1.1" 200 638/0 0.032 - - 88K Jan 30 16:20:27 sogod [1976]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/4561732055696420546167205B265E25242340215D65646635643735352D336163362D336133302D393066362D356262613536333938306436.ics/raw HTTP/1.1" 200 882/0 0.007 - - 64K Jan 30 16:21:57 sogod [1976]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/4561732055696420546167205B265E25242340215D65646635643735352D336163362D336133302D393066362D356262613536333938306436.ics/view HTTP/1.1" 200 296/0 0.044 - - 516K Jan 30 16:22:04 sogod [1971]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.015 - - 4K Jan 30 16:22:04 sogod [1973]: 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.024 - - 0 Jan 30 16:22:04 sogod [1978]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454167325 HTTP/1.1" 200 57/0 0.029 - - 0 Jan 30 16:22:04 sogod [1976]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/batchDelete?ids=4561732055696420546167205B265E25242340215D65646635643735352D336163362D336133302D393066362D356262613536333938306436.ics HTTP/1.1" 204 0/0 0.062 - - 324K Jan 30 16:22:26 sogod [1976]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160205&filterpopup=view_all HTTP/1.1" 200 301/0 0.007 - - 0 Jan 30 16:22:26 sogod [1978]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454167347 HTTP/1.1" 200 57/0 0.012 - - 0 Jan 30 16:22:28 sogod [1978]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/monthview?day=20160131 HTTP/1.1" 200 1257/0 0.013 6537 80% 12K Jan 30 16:22:28 sogod [1976]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/dateselector?day=20160131 HTTP/1.1" 200 772/0 0.020 9375 91% 152K Jan 30 16:22:28 sogod [1976]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20151228&ed=20160131&view=monthview HTTP/1.1" 200 564/0 0.008 - - 4K Jan 30 16:24:27 sogod [1960]: <0x0x2dc79cc[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 30 16:24:27 sogod [1960]: <0x0x2e0d4fc[WOWatchDogChild]> sending terminate signal to pid 1978 Jan 30 16:24:27 sogod [1960]: <0x0x2de877c[WOWatchDogChild]> sending terminate signal to pid 1977 Jan 30 16:24:27 sogod [1960]: <0x0x2de1664[WOWatchDogChild]> sending terminate signal to pid 1976 Jan 30 16:24:27 sogod [1960]: <0x0x2dde1ec[WOWatchDogChild]> sending terminate signal to pid 1973 Jan 30 16:24:27 sogod [1960]: <0x0x2dd2c34[WOWatchDogChild]> sending terminate signal to pid 1971 Jan 30 16:24:27 sogod [1960]: <0x0x2dc79cc[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 30 16:24:27 sogod [1960]: <0x0x2dc79cc[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 30 16:24:27 sogod [1960]: <0x0x2dde1ec[WOWatchDogChild]> child 1973 exited Jan 30 16:24:27 sogod [1960]: <0x0x2dc79cc[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 30 16:24:27 sogod [1960]: <0x0x2dc79cc[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 30 16:24:27 sogod [1960]: <0x0x2e0d4fc[WOWatchDogChild]> child 1978 exited Jan 30 16:24:27 sogod [1960]: <0x0x2dc79cc[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 30 16:24:27 sogod [1960]: <0x0x2de877c[WOWatchDogChild]> child 1977 exited Jan 30 16:24:27 sogod [1960]: <0x0x2dc79cc[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 30 16:24:27 sogod [1960]: <0x0x2dd2c34[WOWatchDogChild]> child 1971 exited Jan 30 16:24:28 sogod [1960]: <0x0x2dc79cc[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 30 16:24:28 sogod [1960]: <0x0x2de1664[WOWatchDogChild]> child 1976 exited Jan 30 16:24:28 sogod [1960]: <0x0x2dc79cc[WOWatchDog]> all children exited. We now terminate. Jan 30 16:24:29 sogod [2639]: version 2.3.5 (build root@shiva.inverse 201601051534) -- starting Jan 30 16:24:29 sogod [2639]: vmem size check enabled: shutting down app when vmem > 384 MB Jan 30 16:24:29 sogod [2639]: <0x0xb7d3829c[SOGoProductLoader]> SOGo products loaded from '/usr/lib/GNUstep/SOGo': Jan 30 16:24:29 sogod [2639]: <0x0xb7d3829c[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 30 16:24:29 sogod [2639]: <0x0xb7e06094[WOWatchDog]> listening on 127.0.0.1:20000 Jan 30 16:24:29 sogod [2639]: <0x0xb7e06094[WOWatchDog]> watchdog process pid: 2639 Jan 30 16:24:29 sogod [2639]: <0x0xb742b8c0[WOWatchDogChild]> watchdog request timeout set to 10 minutes Jan 30 16:24:29 sogod [2639]: <0x0xb7e06094[WOWatchDog]> preparing 5 children Jan 30 16:24:29 sogod [2639]: <0x0xb7e06094[WOWatchDog]> child spawned with pid 2640 Jan 30 16:24:29 sogod [2639]: <0x0xb7e06094[WOWatchDog]> child spawned with pid 2641 Jan 30 16:24:29 sogod [2639]: <0x0xb7e06094[WOWatchDog]> child spawned with pid 2642 Jan 30 16:24:29 sogod [2639]: <0x0xb7e06094[WOWatchDog]> child spawned with pid 2643 Jan 30 16:24:29 sogod [2639]: <0x0xb7e06094[WOWatchDog]> child spawned with pid 2644 Jan 30 16:27:06 sogod [2644]: <0x0xb7ca0d84[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 30 16:27:06 sogod [2644]: <0x0xb7ca0d84[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 30 16:27:07 sogod [2644]: <0x0xb7f64dbc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mond_XXX_local_a84_1c5cc2fe_0 Jan 30 16:27:07 sogod [2644]: [WARN] <0x0xb80a1214[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 30 16:27:07 sogod [2644]: [WARN] <0x0xb80a1214[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 30 16:27:07 sogod [2644]: [WARN] <0x0xb80a1214[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 30 16:27:07 sogod [2644]: <0x0xb7f64dbc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 mond_XXX_local_a84_1c5cc2fe_0 0 Jan 30 16:27:07 sogod [2644]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.589 - - 3M Jan 30 16:27:07 sogod [2644]: <0x0xb7fd0754[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mail%2F5036bb35162fab56c80e0000b79f221a 3 3-11 vevent%2Fpersonal 4 1454154624 vcard%2Fpersonal 0 1454152712 mail%2F981b3a01552fab56d60e0000b79f221a 3 4-13 Jan 30 16:27:07 sogod [2644]: <0x0xb7fc6b8c[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 30 16:27:08 sogod [2644]: <0x0xb7fc6b8c[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 30 16:27:08 sogod [2644]: <0x0xb7fd0754[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 Email mail%2F5036bb35162fab56c80e0000b79f221a 0 1 Calendar vevent%2Fpersonal 1 1 Contacts vcard%2Fpersonal 264 1 Email mail%2F981b3a01552fab56d60e0000b79f221a 0 Jan 30 16:27:08 sogod [2644]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=GetItemEstimate HTTP/1.1" 200 235/223 0.635 - - 144K Jan 30 16:27:08 sogod [2644]: <0x0xb7f8e52c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454154624 vevent%2Fpersonal 5 4 1 2048 1 1 xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAEAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAEAAAAAAAAAxP///w== 20160130T152707Z 20160208T070000Z From mobile 4561732055696420546167205B265E25242340215D65646635643735352D336163362D336133302D393066362D356262613536333938306436 test location 20160208T080000Z 1 0 2 15 Jan 30 16:27:08 sogod [2644]: <0x0xb7f8e52c[SOGoActiveSyncDispatcher]> Cache cleanup needed for device IMEI357909041390858 - user: testuser syncKey: 1454154624 cache: 1454155512 Jan 30 16:27:08 sogod [2644]: <0x0xb7f8e52c[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: DELETE 040000008200E00074C5B7101A82E0080000000080F034A615FED0010000000000000000010000000CC8E08ED08644CEB20B983234A39DB3.ics Jan 30 16:27:08 sogod [2644]: <0x0xb7f8e52c[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: CHANGE A81-56AC9500-3-4FA67300 Jan 30 16:27:08 sogod [2644]: <0x0xb7f8e52c[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: ADD 4561732055696420546167205B265E25242340215D65646635643735352D336163362D336133302D393066362D356262613536333938306436 Jan 30 16:27:08 sogod [2644]: <0x0xb7f8e52c[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 30 16:27:08 sogod [2644]: <0x0xb7f8e52c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar 1454167628 vevent%2Fpersonal 1 1 4561732055696420546167205B265E25242340215D65646635643735352D336163362D336133302D393066362D356262613536333938306436 1 040000008200E00074C5B7101A82E0080000000080F034A615FED0010000000000000000010000000CC8E08ED08644CEB20B983234A39DB3.ics A81-56AC9500-3-4FA67300 0 20160130T105006Z 20160131T120000Z 20160131T130000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 from sogo test location A81-56AC9500-3-4FA67300 0 15 1 19 for testing purpose 1 4561732055696420546167205B265E25242340215D65646635643735352D336163362D336133302D393066362D356262613536333938306436 0 20160208T070000Z 20160208T080000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 From mobile test location 4561732055696420546167205B265E25242340215D65646635643735352D336163362D336133302D393066362D356262613536333938306436 0 15 1 Jan 30 16:27:08 sogod [2644]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 1348/570 0.175 - - 1M Jan 30 16:27:47 sogod [2644]: <0x0xb7f912dc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mond_XXX_local_a84_1c5cc2fe_0 Jan 30 16:27:48 sogod [2644]: <0x0xb7f912dc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 mond_XXX_local_a84_1c5cc2fe_0 0 Jan 30 16:27:48 sogod [2644]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.317 - - 0 Jan 30 16:27:48 sogod [2644]: <0x0xb7f92064[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mail%2F5036bb35162fab56c80e0000b79f221a 3 3-11 vevent%2Fpersonal 4 1454154624 vcard%2Fpersonal 0 1454152712 mail%2F981b3a01552fab56d60e0000b79f221a 3 4-13 Jan 30 16:27:48 sogod [2644]: <0x0xb8014bdc[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 30 16:27:48 sogod [2644]: <0x0xb8014bdc[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 30 16:27:48 sogod [2644]: <0x0xb7f92064[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 Email mail%2F5036bb35162fab56c80e0000b79f221a 0 1 Calendar vevent%2Fpersonal 2 1 Contacts vcard%2Fpersonal 264 1 Email mail%2F981b3a01552fab56d60e0000b79f221a 0 Jan 30 16:27:48 sogod [2644]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=GetItemEstimate HTTP/1.1" 200 235/223 0.635 - - 0 Jan 30 16:27:49 sogod [2644]: <0x0xb7f9cf54[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454154624 vevent%2Fpersonal 5 4 1 2048 1 1 xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAEAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAEAAAAAAAAAxP///w== 20160130T152748Z 20160208T070000Z From mobile 4561732055696420546167205B265E25242340215D65646635643735352D336163362D336133302D393066362D356262613536333938306436 test location 20160208T080000Z 1 0 2 15 Jan 30 16:27:49 sogod [2644]: <0x0xb7f9cf54[SOGoActiveSyncDispatcher]> Cache cleanup needed for device IMEI357909041390858 - user: testuser syncKey: 1454154624 cache: 1454167628 Jan 30 16:27:49 sogod [2644]: <0x0xb7f9cf54[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: DELETE 040000008200E00074C5B7101A82E0080000000080F034A615FED0010000000000000000010000000CC8E08ED08644CEB20B983234A39DB3.ics Jan 30 16:27:49 sogod [2644]: <0x0xb7f9cf54[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: CHANGE A81-56AC9500-3-4FA67300 Jan 30 16:27:49 sogod [2644]: <0x0xb7f9cf54[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: ADD 4561732055696420546167205B265E25242340215D65646635643735352D336163362D336133302D393066362D356262613536333938306436 Jan 30 16:27:49 sogod [2644]: <0x0xb7f9cf54[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 30 16:27:49 sogod [2644]: <0x0xb7f9cf54[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar 1454167669 vevent%2Fpersonal 1 1 4561732055696420546167205B265E25242340215D65646635643735352D336163362D336133302D393066362D356262613536333938306436 1 040000008200E00074C5B7101A82E0080000000080F034A615FED0010000000000000000010000000CC8E08ED08644CEB20B983234A39DB3.ics A81-56AC9500-3-4FA67300 0 20160130T105006Z 20160131T120000Z 20160131T130000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 from sogo test location A81-56AC9500-3-4FA67300 0 15 1 19 for testing purpose 1 4561732055696420546167205B265E25242340215D65646635643735352D336163362D336133302D393066362D356262613536333938306436 0 20160208T070000Z 20160208T080000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 From mobile test location 4561732055696420546167205B265E25242340215D65646635643735352D336163362D336133302D393066362D356262613536333938306436 0 15 1 Jan 30 16:27:49 sogod [2644]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 1348/570 0.087 - - 460K Jan 30 16:28:30 sogod [2644]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454167710 HTTP/1.1" 200 57/0 0.079 - - 120K Jan 30 16:28:30 sogod [2642]: <0x0xb7c9810c[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 30 16:28:30 sogod [2642]: <0x0xb7c9810c[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 30 16:28:30 sogod [2643]: <0x0xb7f4259c[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 30 16:28:30 sogod [2643]: <0x0xb7f4259c[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 30 16:28:30 sogod [2641]: <0x0xb7c9811c[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 30 16:28:30 sogod [2641]: <0x0xb7c9811c[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 30 16:28:30 sogod [2641]: [WARN] <0x0xb7f78034[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 30 16:28:30 sogod [2641]: [WARN] <0x0xb7f78034[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 30 16:28:30 sogod [2641]: [WARN] <0x0xb7f78034[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 30 16:28:30 sogod [2642]: [WARN] <0x0xb7f77dbc[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 30 16:28:30 sogod [2642]: [WARN] <0x0xb7f77dbc[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 30 16:28:30 sogod [2642]: [WARN] <0x0xb7f77dbc[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 30 16:28:30 sogod [2643]: [WARN] <0x0xb7f77924[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 30 16:28:30 sogod [2643]: [WARN] <0x0xb7f77924[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 30 16:28:30 sogod [2643]: [WARN] <0x0xb7f77924[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 30 16:28:30 sogod [2642]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.232 - - 0M Jan 30 16:28:30 sogod [2643]: 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.259 - - 1M Jan 30 16:28:30 sogod [2641]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/batchDelete?ids=A81-56AC9500-3-4FA67300.ics HTTP/1.1" 204 0/0 0.322 - - 3M Jan 30 16:30:13 sogod [2641]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454167814 HTTP/1.1" 200 2/0 0.006 - - 40K Jan 30 16:30:13 sogod [2641]: 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.019 - - 48K Jan 30 16:30:13 sogod [2642]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.010 - - 8K Jan 30 16:30:13 sogod [2641]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160131&filterpopup=view_all HTTP/1.1" 200 375/0 0.024 - - 16K Jan 30 16:30:13 sogod [2643]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/monthview?day=20160131 HTTP/1.1" 200 1257/0 0.132 6537 80% 1M Jan 30 16:30:13 sogod [2643]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20151228&ed=20160131&view=monthview HTTP/1.1" 200 288/0 0.007 - - 12K Jan 30 16:31:49 sogod [2641]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160131&filterpopup=view_all HTTP/1.1" 200 375/0 0.032 - - 4K Jan 30 16:31:49 sogod [2643]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454167910 HTTP/1.1" 200 2/0 0.041 - - 0 Jan 30 16:31:49 sogod [2643]: 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 30 16:31:50 sogod [2643]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.004 - - 20K Jan 30 16:31:50 sogod [2642]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/monthview?day=20160131 HTTP/1.1" 200 1257/0 0.125 6537 80% 1M Jan 30 16:31:50 sogod [2642]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20151228&ed=20160131&view=monthview HTTP/1.1" 200 288/0 0.008 - - 48K Jan 30 16:31:54 sogod [2642]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/monthview?day=20160201 HTTP/1.1" 200 1211/0 0.011 6560 81% 64K Jan 30 16:31:54 sogod [2642]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160201&ed=20160306&view=monthview HTTP/1.1" 200 638/0 0.010 - - 44K Jan 30 16:31:59 sogod [2643]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454167920 HTTP/1.1" 200 2/0 0.004 - - 0 Jan 30 16:31:59 sogod [2643]: 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.004 - - 0 Jan 30 16:31:59 sogod [2643]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.003 - - 0 Jan 30 16:31:59 sogod [2642]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/batchDelete?ids=4561732055696420546167205B265E25242340215D65646635643735352D336163362D336133302D393066362D356262613536333938306436.ics HTTP/1.1" 204 0/0 0.058 - - 876K Jan 30 16:33:08 sogod [2642]: SOGoUserHomePage user 'testuser' logged off Jan 30 16:33:08 sogod [2642]: 192.168.10.181 "GET /SOGo/so/testuser/logoff HTTP/1.1" 302 0/0 0.010 - - 116K Jan 30 16:33:08 sogod [2642]: 192.168.10.181 "GET /SOGo/so/ HTTP/1.1" 200 4631/0 0.068 13454 65% 904K Jan 30 16:33:19 sogod [2639]: <0x0xb7e06094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 30 16:33:19 sogod [2639]: <0x0xb7e5414c[WOWatchDogChild]> sending terminate signal to pid 2644 Jan 30 16:33:19 sogod [2639]: <0x0xb7e4bbc4[WOWatchDogChild]> sending terminate signal to pid 2643 Jan 30 16:33:19 sogod [2639]: <0x0xb7c92554[WOWatchDogChild]> sending terminate signal to pid 2642 Jan 30 16:33:19 sogod [2639]: <0x0xb7e26e44[WOWatchDogChild]> sending terminate signal to pid 2641 Jan 30 16:33:19 sogod [2639]: <0x0xb7e1fd2c[WOWatchDogChild]> sending terminate signal to pid 2640 Jan 30 16:33:19 sogod [2639]: <0x0xb7e06094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 30 16:33:19 sogod [2639]: <0x0xb7e06094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 30 16:33:19 sogod [2639]: <0x0xb7e06094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 30 16:33:19 sogod [2639]: <0x0xb7e4bbc4[WOWatchDogChild]> child 2643 exited Jan 30 16:33:19 sogod [2639]: <0x0xb7e06094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 30 16:33:19 sogod [2639]: <0x0xb7e26e44[WOWatchDogChild]> child 2641 exited Jan 30 16:33:19 sogod [2639]: <0x0xb7e06094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 30 16:33:19 sogod [2639]: <0x0xb7e5414c[WOWatchDogChild]> child 2644 exited Jan 30 16:33:19 sogod [2639]: <0x0xb7e06094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 30 16:33:19 sogod [2639]: <0x0xb7e1fd2c[WOWatchDogChild]> child 2640 exited Jan 30 16:33:20 sogod [2639]: <0x0xb7e06094[WOWatchDog]> Terminating with SIGINT or SIGTERM Jan 30 16:33:20 sogod [2639]: <0x0xb7c92554[WOWatchDogChild]> child 2642 exited Jan 30 16:33:20 sogod [2639]: <0x0xb7e06094[WOWatchDog]> all children exited. We now terminate. Jan 30 16:33:21 sogod [2900]: version 2.3.5 (build root@shiva.inverse 201601051534) -- starting Jan 30 16:33:21 sogod [2900]: vmem size check enabled: shutting down app when vmem > 384 MB Jan 30 16:33:21 sogod [2900]: <0x0xb85a229c[SOGoProductLoader]> SOGo products loaded from '/usr/lib/GNUstep/SOGo': Jan 30 16:33:21 sogod [2900]: <0x0xb85a229c[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 30 16:33:21 sogod [2900]: <0x0xb8670094[WOWatchDog]> listening on 127.0.0.1:20000 Jan 30 16:33:21 sogod [2900]: <0x0xb8670094[WOWatchDog]> watchdog process pid: 2900 Jan 30 16:33:21 sogod [2900]: <0x0xb747f8c0[WOWatchDogChild]> watchdog request timeout set to 10 minutes Jan 30 16:33:21 sogod [2900]: <0x0xb8670094[WOWatchDog]> preparing 5 children Jan 30 16:33:21 sogod [2900]: <0x0xb8670094[WOWatchDog]> child spawned with pid 2901 Jan 30 16:33:21 sogod [2900]: <0x0xb8670094[WOWatchDog]> child spawned with pid 2902 Jan 30 16:33:21 sogod [2900]: <0x0xb8670094[WOWatchDog]> child spawned with pid 2903 Jan 30 16:33:21 sogod [2900]: <0x0xb8670094[WOWatchDog]> child spawned with pid 2904 Jan 30 16:33:21 sogod [2900]: <0x0xb8670094[WOWatchDog]> child spawned with pid 2905 Jan 30 16:36:02 sogod [2905]: <0x0xb850ad84[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 30 16:36:02 sogod [2905]: <0x0xb850ad84[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 30 16:36:02 sogod [2905]: <0x0xb87cedcc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mond_XXX_local_a84_1c5cc2fe_0 Jan 30 16:36:02 sogod [2905]: [WARN] <0x0xb890b1dc[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 30 16:36:02 sogod [2905]: [WARN] <0x0xb890b1dc[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 30 16:36:02 sogod [2905]: [WARN] <0x0xb890b1dc[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 30 16:36:02 sogod [2905]: <0x0xb87cedcc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 mond_XXX_local_a84_1c5cc2fe_0 0 Jan 30 16:36:02 sogod [2905]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.674 - - 3M Jan 30 16:36:03 sogod [2905]: <0x0xb883bd0c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mail%2F5036bb35162fab56c80e0000b79f221a 3 3-11 vevent%2Fpersonal 4 1454154624 vcard%2Fpersonal 0 1454152712 mail%2F981b3a01552fab56d60e0000b79f221a 3 4-13 Jan 30 16:36:03 sogod [2905]: <0x0xb881c2dc[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 30 16:36:03 sogod [2905]: <0x0xb881c2dc[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 30 16:36:03 sogod [2905]: <0x0xb883bd0c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 Email mail%2F5036bb35162fab56c80e0000b79f221a 0 1 Calendar vevent%2Fpersonal 0 1 Contacts vcard%2Fpersonal 264 1 Email mail%2F981b3a01552fab56d60e0000b79f221a 0 Jan 30 16:36:03 sogod [2905]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=GetItemEstimate HTTP/1.1" 200 235/223 0.641 - - 144K Jan 30 16:36:04 sogod [2905]: <0x0xb87fc0e4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454154624 vevent%2Fpersonal 5 4 1 2048 1 A81-56AC9500-3-4FA67300 Jan 30 16:36:04 sogod [2905]: <0x0xb87fc0e4[SOGoActiveSyncDispatcher]> Cache cleanup needed for device IMEI357909041390858 - user: testuser syncKey: 1454154624 cache: 1454167669 Jan 30 16:36:04 sogod [2905]: <0x0xb87fc0e4[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: DELETE 040000008200E00074C5B7101A82E0080000000080F034A615FED0010000000000000000010000000CC8E08ED08644CEB20B983234A39DB3.ics Jan 30 16:36:04 sogod [2905]: <0x0xb87fc0e4[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: DELETE A81-56AC9500-3-4FA67300 Jan 30 16:36:04 sogod [2905]: <0x0xb87fc0e4[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: DELETE 4561732055696420546167205B265E25242340215D65646635643735352D336163362D336133302D393066362D356262613536333938306436 Jan 30 16:36:04 sogod [2905]: <0x0xb87fc0e4[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 30 16:36:04 sogod [2905]: <0x0xb87fc0e4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar 1454167919 vevent%2Fpersonal 1 A81-56AC9500-3-4FA67300 1 040000008200E00074C5B7101A82E0080000000080F034A615FED0010000000000000000010000000CC8E08ED08644CEB20B983234A39DB3.ics A81-56AC9500-3-4FA67300 4561732055696420546167205B265E25242340215D65646635643735352D336163362D336133302D393066362D356262613536333938306436 Jan 30 16:36:04 sogod [2905]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 371/114 0.043 - - 68K Jan 30 16:36:04 sogod [2905]: <0x0xb883c12c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454152712 vcard%2Fpersonal 5 0 1 1024 1 Jan 30 16:36:04 sogod [2905]: <0x0xb883c12c[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 30 16:36:04 sogod [2905]: <0x0xb883c12c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Contacts 1454167187 vcard%2Fpersonal 1 A83-56AC9C00-409-35EC8C00 Jan 30 16:36:04 sogod [2905]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 94/82 0.045 - - 0 Jan 30 16:36:05 sogod [2905]: <0x0xb87ee064[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: RnJvbTogInRlc3R1c2VyQGl2YmhvbWUubG9jYWwiIDx0ZXN0dXNlckBpdmJob21lLmxvY2FsPg0KVG86IDxhZG1pbkBpdmJob21lLmR5bmRucy5vcmc+DQpTdWJqZWN0OiBDYWxlbmRhci52Y3MNCkRhdGU6IFNhdCwgMzAgSmFuIDIwMTYgMTY6MzU6NTYgKzAxMDANCk1lc3NhZ2UtSUQ6IDxjanpOdGNtZDJad2pANG4yMk1yUGg+DQpYLU1haWxlcjogRUFTIFZlcnNpb24gMS4wMA0KTUlNRS1WZXJzaW9uOiAxLjANCkNvbnRlbnQtTGFuZ3VhZ2U6IGktZGVmYXVsdA0KQ29udGVudC1UeXBlOiBtdWx0aXBhcnQvbWl4ZWQ7DQogIGJvdW5kYXJ5PSJFUE9DMzIteEZuWmJ2NEpsazdWcWw5eGZCY2JNWidrNC1kRC1jcHMsR25QZENNTFQyajcnMCd6Ig0KDQpUaGlzIGlzIGEgTUlNRSBNZXNzYWdlDQoNCi0tRVBPQzMyLXhGblpidjRKbGs3VnFsOXhmQmNiTVonazQtZEQtY3BzLEduUGRDTUxUMmo3JzAneg0KQ29udGVudC1UeXBlOiB0ZXh0L3BsYWluOyBjaGFyc2V0PVVURi04DQpDb250ZW50LURpc3Bvc2l0aW9uOiBpbmxpbmUNCkNvbnRlbnQtVHJhbnNmZXItRW5jb2Rpbmc6IHF1b3RlZC1wcmludGFibGUNCg0KLS1FUE9DMzIteEZuWmJ2NEpsazdWcWw5eGZCY2JNWidrNC1kRC1jcHMsR25QZENNTFQyajcnMCd6DQpDb250ZW50LURlc2NyaXB0aW9uOiBDYWxlbmRhci52Y3MNCkNvbnRlbnQtVHlwZTogdGV4dC94LXZDYWxlbmRhcg0KQ29udGVudC1EaXNwb3NpdGlvbjogYXR0YWNobWVudDsNCiBmaWxlbmFtZSo9VVRGLTgnJ0NhbGVuZGFyLnZjcw0KQ29udGVu dC1UcmFuc2Zlci1FbmNvZGluZzogYmFzZTY0DQoNClFrVkhTVTQ2VmtOQlRFVk9SRUZTRFFwV1JWSlRTVTlPT2pFdU1BMEtRa1ZIU1U0NlZrVldSVTVVRFFwVg0KU1VRNlpXUm1OV1EzTlRVdE0yRmpOaTB6WVRNd0xUa3daall0TldKaVlUVTJNems0TUdRMkRRcFRWVTFODQpRVkpaT2taeWIyMGdiVzlpYVd4bERRcEVWRk5VUVZKVU9qSXdNVFl3TWpBNFZEQTNNREF3TUZvTkNrUlUNClJVNUVPakl3TVRZd01qQTRWREE0TURBd01Gb05DbGd0UlZCUFEwRkhSVTVFUVVWT1ZGSlpWRmxRUlRwQg0KVUZCUFNVNVVUVVZPVkEwS1EweEJVMU02VUZWQ1RFbEREUXBNVDBOQlZFbFBUanAwWlhOMElHeHZZMkYwDQphVzl1RFFwWUxWTlpUVUpKUVU0dFJGUlRWRUZOVURveU1ERTJNREV6TUZReE5UQTJORGRhRFFwVFJWRlYNClJVNURSVG94RFFwWUxVMUZWRWhQUkRwT1QwNUZEUXBCUVV4QlVrMDdWRmxRUlQxWUxVVlFUME5UVDFWTw0KUkRveU1ERTJNREl3T0ZRd05qUTFNREJhT3pzN0RRcE1RVk5VTFUxUFJFbEdTVVZFT2pJd01UWXdNVE13DQpWREUxTURZME4xb05DbEJTU1U5U1NWUlpPalVOQ2xndFUxbE5Ra2xCVGkxTVZVbEVPamtOQ2xSU1FVNVQNClVEb3dEUXBZTFZWVFJWSkVRVlJCTFVsT1ZEb3dEUXBGVGtRNlZrVldSVTVVRFFwRlRrUTZWa05CVEVWTw0KUkVGU0RRbz0NCi0tRVBPQzMyLXhGblpidjRKbGs3VnFsOXhmQmNiTVonazQtZEQtY3BzLEduUGRDTUxUMmo3JzAnei0tDQo= 2016-01-30 16:36:05.549 sogod[2905] _consume[1350]: error try to read over buffer len self->dataIdx 115 _cnt 1 byteLen 115 Jan 30 16:36:05 sogod [2905]: <0x0xb8844e5c[NGMimeBodyPartParser]> WARNING(-[NGMimePartParser parseHeader]:713): 2 an error occured during body parsing (maybe end of stream) Jan 30 16:36:06 sogod [2905]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&SaveInSent=T&Cmd=SendMail HTTP/1.1" 200 0/1490 1.249 - - 288K Jan 30 16:36:07 sogod [2905]: <0x0xb881d0d4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 4-13 mail%2F981b3a01552fab56d60e0000b79f221a 5 3 2 2048 1 Jan 30 16:36:08 sogod [2905]: <0x0xb884bde4[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 30 16:36:08 sogod [2905]: <0x0xb881d0d4[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (2) - lastObject: 1-10 Jan 30 16:36:08 sogod [2905]: <0x0xb881d0d4[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (1) - lastObject: 4-14 Jan 30 16:36:08 sogod [2905]: <0x0xb881d0d4[SOGoActiveSyncDispatcher]> EAS - found in cache: 0 k = 0 Jan 30 16:36:08 sogod [2905]: <0x0xb881d0d4[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 4-14 Jan 30 16:36:08 sogod [2905]: <0x0xb881d0d4[SOGoActiveSyncDispatcher]> EAS - ADD! Jan 30 16:36:08 sogod [2905]: <0x0xb87cc934[NGImap4ResponseParser]> ERROR(-[NGImap4ResponseParser _parseData]): got content size '0'! Jan 30 16:36:08 sogod [2905]: <0x0xb87cc934[NGImap4ResponseParser]> ERROR(-[NGImap4ResponseParser _parseBodyContent]): got no data. Jan 30 16:36:08 sogod [2905]: <0x0xb87cc934[NGImap4ResponseParser]> ERROR: got no body content for key: 'body[1]' Jan 30 16:36:08 sogod [2905]: [ERROR] <0xB882E0CC[SOGoMailObject]:4> got no data for key: body[1] Jan 30 16:36:08 sogod [2905]: <0x0xb881d0d4[SOGoActiveSyncDispatcher]> EAS - first uid added 4 Jan 30 16:36:08 sogod [2905]: <0x0xb881d0d4[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 30 16:36:08 sogod [2905]: <0x0xb881d0d4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Email 4-14 mail%2F981b3a01552fab56d60e0000b79f221a 1 4 "admin@XXX.dyndns.org" <admin@XXX.dyndns.org> "ein testuser" <testuser@XXX.local> Calendar.vcs Calendar.vcs 2016-01-30T15:35:56.000Z testuser 1 1 IPM.Note urn:content-classes:message 65001 Calendar.vcs mail/Sent/4/2 1 628 0 1 Jan 30 16:36:08 sogod [2905]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 390/99 0.723 - - 148K Jan 30 16:36:27 sogod [2905]: <0x0xb87f08b4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mond_XXX_local_a84_1c5cc2fe_0 Jan 30 16:36:27 sogod [2905]: <0x0xb87f08b4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 mond_XXX_local_a84_1c5cc2fe_0 0 Jan 30 16:36:27 sogod [2905]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.383 - - 0 Jan 30 16:36:27 sogod [2905]: <0x0xb88084cc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mail%2F5036bb35162fab56c80e0000b79f221a 3 3-11 vevent%2Fpersonal 4 1454167919 vcard%2Fpersonal 0 1454167187 mail%2F981b3a01552fab56d60e0000b79f221a 3 4-14 Jan 30 16:36:28 sogod [2905]: <0x0xb87e0e14[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 30 16:36:28 sogod [2905]: <0x0xb87e0e14[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 30 16:36:28 sogod [2905]: <0x0xb88084cc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 Email mail%2F5036bb35162fab56c80e0000b79f221a 0 1 Calendar vevent%2Fpersonal 0 1 Contacts vcard%2Fpersonal 264 1 Email mail%2F981b3a01552fab56d60e0000b79f221a 1 Jan 30 16:36:28 sogod [2905]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=GetItemEstimate HTTP/1.1" 200 235/223 0.699 - - 4K Jan 30 16:36:28 sogod [2905]: <0x0xb883bd0c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454167187 vcard%2Fpersonal 5 0 1 1024 1 Jan 30 16:36:28 sogod [2905]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/82 0.028 - - 0 Jan 30 16:36:29 sogod [2905]: <0x0xb8833aa4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 4-14 mail%2F981b3a01552fab56d60e0000b79f221a 5 3 2 2048 1 Jan 30 16:36:29 sogod [2905]: <0x0xb87fc464[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 30 16:36:29 sogod [2905]: <0x0xb8833aa4[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (3) - lastObject: 4-14 Jan 30 16:36:29 sogod [2905]: <0x0xb8833aa4[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (1) - lastObject: 4-14 Jan 30 16:36:29 sogod [2905]: <0x0xb8833aa4[SOGoActiveSyncDispatcher]> EAS - found in cache: 0 k = 0 Jan 30 16:36:29 sogod [2905]: <0x0xb8833aa4[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 4-14 Jan 30 16:36:29 sogod [2905]: <0x0xb8833aa4[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 30 16:36:29 sogod [2905]: <0x0xb8833aa4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Email 5-14 mail%2F981b3a01552fab56d60e0000b79f221a 1 Jan 30 16:36:29 sogod [2905]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 75/99 0.652 - - 0 Jan 30 16:38:00 sogod [2905]: SOGoRootPage successful login from '192.168.10.181' for user 'testuser' - expire = -1 grace = -1 Jan 30 16:38:00 sogod [2905]: 192.168.10.181 "POST /SOGo/connect HTTP/1.1" 200 27/43 0.059 - - 904K Jan 30 16:38:00 sogod [2905]: 192.168.10.181 "GET /SOGo/so/testuser HTTP/1.1" 302 0/0 0.004 - - 20K Jan 30 16:38:00 sogod [2905]: 192.168.10.181 "GET /SOGo/so/testuser/view HTTP/1.1" 302 0/0 0.005 - - 8K Jan 30 16:38:00 sogod [2905]: 192.168.10.181 "GET /SOGo/so/testuser/Mail HTTP/1.1" 302 0/0 0.002 - - 0 Jan 30 16:38:01 sogod [2905]: 192.168.10.181 "GET /SOGo/so/testuser/Mail/view HTTP/1.1" 200 11324/0 0.661 39676 71% 1M Jan 30 16:38:02 sogod [2905]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454168282 HTTP/1.1" 200 2/0 0.006 - - 24K Jan 30 16:38:02 sogod [2905]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/mailboxes HTTP/1.1" 200 341/0 0.226 - - 4K Jan 30 16:38:02 sogod [2904]: <0x0xb850210c[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 30 16:38:02 sogod [2904]: <0x0xb850210c[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 30 16:38:02 sogod [2904]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/foldersState HTTP/1.1" 200 0/0 0.143 - - 2M Jan 30 16:38:02 sogod [2905]: 192.168.10.181 "POST /SOGo/so/testuser/Mail/0/folderINBOX/unseenCount HTTP/1.1" 200 13/0 0.320 - - 8K Jan 30 16:38:04 sogod [2905]: 192.168.10.181 "GET /SOGo/so/testuser/Mail/0/folderINBOX/expunge HTTP/1.1" 200 2/0 0.238 - - 0 Jan 30 16:38:04 sogod [2905]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar HTTP/1.1" 302 0/0 0.007 - - 0 Jan 30 16:38:04 sogod [2905]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/view HTTP/1.1" 200 14436/0 0.086 54249 73% 288K Jan 30 16:38:05 sogod [2905]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/canAccessContent HTTP/1.1" 204 0/0 0.005 - - 4K Jan 30 16:38:05 sogod [2904]: [WARN] <0x0xb88930ec[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 30 16:38:05 sogod [2904]: [WARN] <0x0xb88930ec[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 30 16:38:05 sogod [2904]: [WARN] <0x0xb88930ec[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 30 16:38:05 sogod [2905]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454168286 HTTP/1.1" 200 2/0 0.003 - - 0 Jan 30 16:38:05 sogod [2904]: 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.044 - - 316K Jan 30 16:38:05 sogod [2902]: <0x0xb850210c[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 30 16:38:05 sogod [2902]: <0x0xb850210c[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 30 16:38:05 sogod [2901]: <0x0xb850210c[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 30 16:38:05 sogod [2901]: <0x0xb850210c[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 30 16:38:05 sogod [2903]: <0x0xb850214c[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds Jan 30 16:38:05 sogod [2903]: <0x0xb850214c[SOGoCache]> Using host(s) '127.0.0.1' as server(s) Jan 30 16:38:05 sogod [2902]: [WARN] <0x0xb87e1e6c[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 30 16:38:05 sogod [2902]: [WARN] <0x0xb87e1e6c[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 30 16:38:05 sogod [2902]: [WARN] <0x0xb87e1e6c[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 30 16:38:05 sogod [2903]: [WARN] <0x0xb87e18b4[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 30 16:38:05 sogod [2903]: [WARN] <0x0xb87e18b4[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 30 16:38:05 sogod [2903]: [WARN] <0x0xb87e18b4[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 30 16:38:05 sogod [2901]: [WARN] <0x0xb87e1dec[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table Jan 30 16:38:05 sogod [2901]: [WARN] <0x0xb87e1dec[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table Jan 30 16:38:05 sogod [2901]: [WARN] <0x0xb87e1dec[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table Jan 30 16:38:05 sogod [2902]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.198 - - 996K Jan 30 16:38:05 sogod [2903]: 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.218 - - 1M Jan 30 16:38:05 sogod [2901]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/monthview HTTP/1.1" 200 1257/0 0.266 6537 80% 2M Jan 30 16:38:05 sogod [2901]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20151228&ed=20160131&view=monthview HTTP/1.1" 200 288/0 0.011 - - 132K Jan 30 16:38:23 sogod [2901]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/import HTTP/1.1" 200 15/1373 0.025 - - 432K Jan 30 16:38:23 sogod [2901]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454168304 HTTP/1.1" 200 2/0 0.006 - - 0 Jan 30 16:38:23 sogod [2901]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/monthview?day=20160130 HTTP/1.1" 200 1257/0 0.008 6537 80% 88K Jan 30 16:38:23 sogod [2901]: 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.005 - - 8K Jan 30 16:38:23 sogod [2901]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20151228&ed=20160131&view=monthview HTTP/1.1" 200 288/0 0.005 - - 0 Jan 30 16:38:36 sogod [2901]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454168317 HTTP/1.1" 200 2/0 0.006 - - 0 Jan 30 16:38:36 sogod [2903]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160130&filterpopup=view_all HTTP/1.1" 200 284/0 0.008 - - 68K Jan 30 16:38:39 sogod [2901]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/dateselector?day=20160208 HTTP/1.1" 200 737/0 0.011 9373 92% 72K Jan 30 16:38:39 sogod [2903]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/monthview?day=20160208 HTTP/1.1" 200 1211/0 0.077 6560 81% 1M Jan 30 16:38:39 sogod [2903]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20160201&ed=20160306&view=monthview HTTP/1.1" 200 547/0 0.009 - - 36K Jan 30 16:38:48 sogod [2903]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/edf5d755-3ac6-3a30-90f6-5bba563980d6/raw HTTP/1.1" 200 495/0 0.009 - - 68K Jan 30 16:39:13 sogod [2903]: <0x0xb88539b4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mond_XXX_local_a84_1c5cc2fe_0 Jan 30 16:39:14 sogod [2903]: <0x0xb88539b4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 mond_XXX_local_a84_1c5cc2fe_0 0 Jan 30 16:39:14 sogod [2903]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.349 - - 932K Jan 30 16:39:14 sogod [2903]: <0x0xb87f33bc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mail%2F5036bb35162fab56c80e0000b79f221a 3 3-11 vevent%2Fpersonal 4 1454167919 vcard%2Fpersonal 0 1454167187 mail%2F981b3a01552fab56d60e0000b79f221a 3 5-14 Jan 30 16:39:14 sogod [2903]: <0x0xb88d3eec[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 30 16:39:15 sogod [2903]: <0x0xb88d3eec[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 30 16:39:15 sogod [2903]: <0x0xb87f33bc[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 Email mail%2F5036bb35162fab56c80e0000b79f221a 0 1 Calendar vevent%2Fpersonal 1 1 Contacts vcard%2Fpersonal 264 1 Email mail%2F981b3a01552fab56d60e0000b79f221a 1 Jan 30 16:39:15 sogod [2903]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=GetItemEstimate HTTP/1.1" 200 235/223 0.689 - - 120K Jan 30 16:39:15 sogod [2903]: <0x0xb85da704[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454167919 vevent%2Fpersonal 5 4 1 2048 1 Jan 30 16:39:15 sogod [2903]: <0x0xb85da704[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 30 16:39:15 sogod [2903]: <0x0xb85da704[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar 1454168303 vevent%2Fpersonal 1 edf5d755-3ac6-3a30-90f6-5bba563980d6.ics 0 20160208T070000Z 20160208T080000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 From mobile test location edf5d755-3ac6-3a30-90f6-5bba563980d6 0 1 Jan 30 16:39:15 sogod [2903]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 484/83 0.050 - - 360K Jan 30 16:39:15 sogod [2903]: <0x0xb87f61e4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454167187 vcard%2Fpersonal 5 0 1 1024 1 Jan 30 16:39:15 sogod [2903]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/82 0.019 - - 0 Jan 30 16:39:15 sogod [2903]: <0x0xb87f7604[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 5-14 mail%2F981b3a01552fab56d60e0000b79f221a 5 3 2 2048 1 Jan 30 16:39:16 sogod [2903]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/99 0.411 - - 0 Jan 30 16:40:25 sogod [2903]: <0x0xb893b29c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mond_XXX_local_a84_1c5cc2fe_0 Jan 30 16:40:25 sogod [2903]: <0x0xb893b29c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 mond_XXX_local_a84_1c5cc2fe_0 0 Jan 30 16:40:25 sogod [2903]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.324 - - 84K Jan 30 16:40:25 sogod [2903]: <0x0xb87e351c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mail%2F5036bb35162fab56c80e0000b79f221a 3 3-11 vevent%2Fpersonal 4 1454168303 vcard%2Fpersonal 0 1454167187 mail%2F981b3a01552fab56d60e0000b79f221a 3 5-14 Jan 30 16:40:26 sogod [2903]: <0x0xb8872aac[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 30 16:40:26 sogod [2903]: <0x0xb8872aac[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 30 16:40:26 sogod [2903]: <0x0xb87e351c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 Email mail%2F5036bb35162fab56c80e0000b79f221a 0 1 Calendar vevent%2Fpersonal 1 1 Contacts vcard%2Fpersonal 264 1 Email mail%2F981b3a01552fab56d60e0000b79f221a 1 Jan 30 16:40:26 sogod [2903]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=GetItemEstimate HTTP/1.1" 200 235/223 0.689 - - 32K Jan 30 16:40:26 sogod [2903]: <0x0xb8509d5c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454168303 vevent%2Fpersonal 5 4 1 2048 1 1 xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAEAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAEAAAAAAAAAxP///w== 20160130T154017Z 20160131T070000Z Event now ota 4561732055696420546167205B265E25242340215D31363438613839322D363730332D336437362D623161352D613132336462373437653764 20160131T080000Z 1 0 2 Jan 30 16:40:26 sogod [2903]: <0x0xb8509d5c[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 30 16:40:26 sogod [2903]: <0x0xb8509d5c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar 1454168426 vevent%2Fpersonal 1 1 4561732055696420546167205B265E25242340215D31363438613839322D363730332D336437362D623161352D613132336462373437653764 1 Jan 30 16:40:26 sogod [2903]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 194/549 0.067 - - 412K Jan 30 16:40:27 sogod [2903]: <0x0xb88c5514[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454167187 vcard%2Fpersonal 5 0 1 1024 1 Jan 30 16:40:27 sogod [2903]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/82 0.018 - - 0 Jan 30 16:40:27 sogod [2903]: <0x0xb890d1b4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 5-14 mail%2F981b3a01552fab56d60e0000b79f221a 5 3 2 2048 1 Jan 30 16:40:27 sogod [2903]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/99 0.406 - - 0 Jan 30 16:40:40 sogod [2901]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/monthview?day=20160101 HTTP/1.1" 200 1257/0 0.006 6537 80% 0 Jan 30 16:40:40 sogod [2903]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/dateselector?day=20160101 HTTP/1.1" 200 766/0 0.022 9375 91% -24K Jan 30 16:40:40 sogod [2903]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20151228&ed=20160131&view=monthview HTTP/1.1" 200 618/0 0.009 - - 0 Jan 30 16:40:44 sogod [2903]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/4561732055696420546167205B265E25242340215D31363438613839322D363730332D336437362D623161352D613132336462373437653764.ics/raw HTTP/1.1" 200 728/0 0.004 - - 0 Jan 30 16:45:03 sogod [2903]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/4561732055696420546167205B265E25242340215D31363438613839322D363730332D336437362D623161352D613132336462373437653764.ics/raw HTTP/1.1" 200 728/0 0.008 - - 0 Jan 30 16:46:12 sogod [2903]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/newevent?day=20160130 HTTP/1.1" 302 0/0 0.009 - - 36K 2016-01-30 16:46:12.587 sogod[2903] File NSCalendarDate.m: 1533. In -[NSCalendarDate initWithYear:month:day:hour:minute:second:timeZone:] invalid minute given - 60 Jan 30 16:46:12 sogod [2903]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal//B57-56ACDB00-5-4E6C6700.ics/editAsAppointment?day=20160130 HTTP/1.1" 200 10432/0 0.138 33009 68% 1M Jan 30 16:46:29 sogod [2903]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/B57-56ACDB00-5-4E6C6700.ics/saveAsAppointment HTTP/1.1" 200 21/802 0.018 - - 28K Jan 30 16:46:29 sogod [2903]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454168790 HTTP/1.1" 200 57/0 0.005 - - 0 Jan 30 16:46:29 sogod [2903]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160130&filterpopup=view_all HTTP/1.1" 200 908/0 0.011 - - 0 Jan 30 16:46:29 sogod [2903]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/monthview?day=20160130 HTTP/1.1" 200 1257/0 0.007 6537 80% 24K Jan 30 16:46:29 sogod [2903]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20151228&ed=20160131&view=monthview HTTP/1.1" 200 864/0 0.007 - - 0 Jan 30 16:46:44 sogod [2903]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/B57-56ACDB00-5-4E6C6700.ics/raw HTTP/1.1" 200 802/0 0.006 - - 0 Jan 30 16:47:24 sogod [2903]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/personal/B57-56ACDB00-5-4E6C6700.ics/view HTTP/1.1" 200 281/0 0.014 - - 0 Jan 30 16:47:26 sogod [2903]: 192.168.10.181 "GET /SOGo/so/testuser/Calendar/personal/B57-56ACDB00-5-4E6C6700.ics/raw HTTP/1.1" 200 802/0 0.006 - - 0 Jan 30 16:48:05 sogod [2903]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/alarmslist?browserTime=1454168886 HTTP/1.1" 200 57/0 0.010 - - 0 Jan 30 16:48:05 sogod [2903]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventslist?asc=true&sort=start&day=20160130&filterpopup=view_all HTTP/1.1" 200 908/0 0.008 - - 0 Jan 30 16:48:05 sogod [2901]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/monthview?day=20160130 HTTP/1.1" 200 1257/0 0.011 6537 80% 0 Jan 30 16:48:05 sogod [2903]: 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 30 16:48:05 sogod [2902]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/activeTasks HTTP/1.1" 200 15/0 0.020 - - 8K Jan 30 16:48:05 sogod [2902]: 192.168.10.181 "POST /SOGo/so/testuser/Calendar/eventsblocks?sd=20151228&ed=20160131&view=monthview HTTP/1.1" 200 864/0 0.016 - - 180K Jan 30 16:48:19 sogod [2902]: <0x0xb85a57ac[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mond_XXX_local_a84_1c5cc2fe_0 Jan 30 16:48:19 sogod [2902]: <0x0xb85a57ac[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 mond_XXX_local_a84_1c5cc2fe_0 0 Jan 30 16:48:19 sogod [2902]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.348 - - 1M Jan 30 16:48:19 sogod [2902]: <0x0xb8525f7c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: mail%2F5036bb35162fab56c80e0000b79f221a 3 3-11 vevent%2Fpersonal 4 1454168303 vcard%2Fpersonal 0 1454167187 mail%2F981b3a01552fab56d60e0000b79f221a 3 5-14 Jan 30 16:48:20 sogod [2902]: <0x0xb886e7fc[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 30 16:48:20 sogod [2902]: <0x0xb886e7fc[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 30 16:48:20 sogod [2902]: <0x0xb8525f7c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: 1 Email mail%2F5036bb35162fab56c80e0000b79f221a 0 1 Calendar vevent%2Fpersonal 3 1 Contacts vcard%2Fpersonal 264 1 Email mail%2F981b3a01552fab56d60e0000b79f221a 1 Jan 30 16:48:20 sogod [2902]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=GetItemEstimate HTTP/1.1" 200 235/223 0.668 - - 120K Jan 30 16:48:20 sogod [2902]: <0x0xb88b602c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: 1454168303 vevent%2Fpersonal 5 4 1 2048 1 Jan 30 16:48:20 sogod [2902]: <0x0xb88b602c[SOGoActiveSyncDispatcher]> Cache cleanup needed for device IMEI357909041390858 - user: testuser syncKey: 1454168303 cache: 1454168426 Jan 30 16:48:20 sogod [2902]: <0x0xb88b602c[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: ADD 4561732055696420546167205B265E25242340215D31363438613839322D363730332D336437362D623161352D613132336462373437653764 Jan 30 16:48:20 sogod [2902]: <0x0xb88b602c[SOGoActiveSyncDispatcher]> EAS - Cache cleanup: CHANGE B57-56ACDB00-5-4E6C6700 Jan 30 16:48:20 sogod [2902]: <0x0xb88b602c[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 30 16:48:20 sogod [2902]: <0x0xb88b602c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: Calendar 1454168789 vevent%2Fpersonal 1 4561732055696420546167205B265E25242340215D31363438613839322D363730332D336437362D623161352D613132336462373437653764 0 20160131T070000Z 20160131T080000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 Event now ota 4561732055696420546167205B265E25242340215D31363438613839322D363730332D336437362D623161352D613132336462373437653764 0 1 B57-56ACDB00-5-4E6C6700 0 20160130T154629Z 20160130T180000Z 20160130T190000Z xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w== 0 from sogo B57-56ACDB00-5-4E6C6700 0 15 1 Jan 30 16:48:20 sogod [2902]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 1020/83 0.070 - - 592K Jan 30 16:54:24 sogod [2902]: SOGoUserHomePage user 'testuser' logged off Jan 30 16:54:24 sogod [2902]: 192.168.10.181 "GET /SOGo/so/testuser/logoff HTTP/1.1" 302 0/0 0.029 - - 740K Jan 30 16:54:25 sogod [2902]: 192.168.10.181 "GET /SOGo/so/ HTTP/1.1" 200 4631/0 0.076 13454 65% 1M