View Issue Details

IDProjectCategoryView StatusLast Update
0004767SOGoWeb Mailpublic2019-07-18 06:20
Reporterondrej.kolin Assigned To 
PrioritynormalSeverityminorReproducibilityhave not tried
Status newResolutionopen 
PlatformServerOSUbuntuOS Version18.04
Product Version4.0.7 
Summary0004767: Profile corruption
Description

User's profile gets corrupted, so its not anymore parsable by JSON. I have an assumption, that this happens after IMAP connection gets broken. Appending log.

This happend twice so far in one week

Steps To Reproduce

No real steps to reproduce. It just occures at some point

TagsNo tags attached.

Activities

ondrej.kolin

ondrej.kolin

2019-06-17 07:56

reporter  

sogo_error.txt (25,136 bytes)   
Jun 17 09:18:53 sogod [5454]: 84.63.38.46 "POST /SOGo/so/drebelo/Mail/0/folderINBOX/view HTTP/1.1" 200 10071/45 1.008 - - -16K
Jun 17 09:18:55 sogod [5454]: [ERROR] <0x0x564517b9ca60[NGImap4ConnectionManager]> IMAP4 login failed:
  host=mail.company.com, user=username, pwd=yes
  url=imaps://username@mail.company.com/?tls=YES
  base=(null)
  base-class=(null))
  = <0x0x5645211f4580[NGImap4Client]: login=username(pwd) address=<0x0x5645211d3490[NGInternetSocketAddress]: host=mail.company.com not-filled>>
Jun 17 09:18:55 sogod [5454]: <0x564527705ee0[SOGoMailAccount]:0> renewing imap4 password
Jun 17 09:18:55 sogod [5454]: [ERROR] <0x0x564517b9ca60[NGImap4ConnectionManager]> IMAP4 login failed:
  host=mail.company.com, user=username, pwd=yes
  url=imaps://username@mail.company.com/?tls=YES
  base=(null)
  base-class=(null))
  = <0x0x56451e740750[NGImap4Client]: login=username(pwd) address=<0x0x56451cdde310[NGInternetSocketAddress]: host=mail.company.com not-filled>>
Jun 17 09:18:55 sogod [5454]: [ERROR] <0x564527705ee0[SOGoMailAccount]:0> Could not connect IMAP4
2019-06-17 09:18:55.939 sogod[5454:5454] GSFromUnicode() No iconv for encoding NSUTF32LittleEndianStringEncoding tried to use UTF-32LE
... message repeats 
2019-06-17 09:18:55.951 sogod[5454:5454] GSFromUnicode() No iconv for encoding sNSUTF32LittleEndianStringEncoding tried to use UTF-32LE
Jun 17 09:18:55 sogod [5454]: 212.201.104.11 "POST /SOGo/so/username/Mail/0/foldershared/folderadministrator/folderpurchase/view HTTP/1.1" 500 4/45 0.022 - - 0
Jun 17 09:19:12 sogod [5426]: <0x0x5645175d0cc0[WOWatchDogChild]> child 5454 exited
Jun 17 09:19:12 sogod [5426]: <0x0x5645175d0cc0[WOWatchDogChild]>  (terminated due to signal 11, coredump)
Jun 17 09:19:12 sogod [5426]: <0x0x564517456ee0[WOWatchDog]> child spawned with pid 17403
Jun 17 09:19:12 sogod [17403]: <0x0x5645175b07a0[WOHttpAdaptor]> notified the watchdog that we are ready
Jun 17 09:19:14 sogod [17403]: <0x0x5645175c2aa0[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Jun 17 09:19:14 sogod [17403]: <0x0x5645175c2aa0[SOGoCache]> Using host(s) 'localhost' as server(s)
Jun 17 09:19:14 sogod [17403]: [ERROR] <0x0x5645172784a0[GSCBufferString]> json parser: Expected value while parsing array, attempting once more after unescaping...
Jun 17 09:19:14 sogod [17403]: [ERROR] <0x0x5645172784a0[GSCBufferString]> total failure. Original string is: {: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}
Jun 17 09:19:14 sogod [17403]: [ERROR] <0x0x564517278410[SOGoSQLUserProfile]> failure parsing json string: '{: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}'
Jun 17 09:19:14 sogod [17403]: <0x0x56451725cf30[NGImap4Client]> TLS started successfully.
Jun 17 09:19:15 sogod [17403]: [ERROR] <0x0x5645172784a0[GSCBufferString]> json parser: Expected value while parsing array, attempting once more after unescaping...
Jun 17 09:19:15 sogod [17403]: [ERROR] <0x0x5645172784a0[GSCBufferString]> total failure. Original string is: {: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}
Jun 17 09:19:15 sogod [17403]: [ERROR] <0x0x564517278410[SOGoSQLUserProfile]> failure parsing json string: '{: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}'
2019-06-17 09:19:17.484 sogod[17403:17403] parseTimeZone: cannot parse time notation 'UT'
Jun 17 09:19:17 sogod [17403]: 212.201.104.11 "POST /SOGo/so/username/Mail/0/foldershared/folderadministrator/folderpurchase/view HTTP/1.1" 200 30153/45 3.017 - - 19M
Jun 17 09:19:21 sogod [17403]: <0x0x564518859950[NGImap4Client]> TLS started successfully.
Jun 17 09:19:22 sogod [17403]: 84.63.38.46 "POST /SOGo/so/drebelo/Mail/unseenCount HTTP/1.1" 200 21/31 1.369 - - 0
Jun 17 09:19:23 sogod [17403]: <0x0x5645188599c0[NGImap4Client]> TLS started successfully.
Jun 17 09:19:23 sogod [17403]: 212.201.104.11 "POST /SOGo/so/lschiesl/Mail/0/folderINBOX/view HTTP/1.1" 200 13493/45 0.936 - - 240K
Jun 17 09:19:33 sogod [17403]: <0x0x56451756ed40[NGImap4Client]> TLS started successfully.
Jun 17 09:19:34 sogod [17403]: 212.201.104.11 "POST /SOGo/so/lschiesl/Mail/unseenCount HTTP/1.1" 200 21/31 0.632 - - 0
Jun 17 09:19:52 sogod [3549]: <0x0x56451a322db0[NGImap4Client]> TLS started successfully.
Jun 17 09:19:53 sogod [17403]: <0x0x5645187f0ca0[NGImap4Client]> TLS started successfully.
Jun 17 09:19:53 sogod [3549]: 212.201.104.11 "POST /SOGo/so/lschiesl/Mail/0/folderINBOX/moveMessages HTTP/1.1" 204 0/82 0.867 - - 0
Jun 17 09:19:53 sogod [17403]: 84.63.38.46 "POST /SOGo/so/drebelo/Mail/0/folderINBOX/view HTTP/1.1" 200 31241/45 1.035 - - 800K
Jun 17 09:19:55 sogod [3549]: <0x0x564519cab640[NGImap4Client]> TLS started successfully.
Jun 17 09:19:55 sogod [3549]: 212.201.104.11 "GET /SOGo/so/lschiesl/Mail/0/folderINBOX/1095/view HTTP/1.1" 200 32140/0 1.917 - - 0
Jun 17 09:19:56 sogod [3549]: <0x0x56451a3234e0[NGImap4Client]> TLS started successfully.
Jun 17 09:19:57 sogod [3549]: 212.201.104.11 "GET /SOGo/so/lschiesl/Mail/0/folderINBOX/1095/2/image001.png HTTP/1.1" 200 185/0 0.779 - - -16K
Jun 17 09:20:06 sogod [17403]: [WARN] <0x0x5645178a6030[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table
Jun 17 09:20:06 sogod [17403]: [WARN] <0x0x5645178a6030[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table
Jun 17 09:20:06 sogod [17403]: [WARN] <0x0x5645178a6030[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table
Jun 17 09:20:06 sogod [3549]: [ERROR] <0x0x564519f381d0[GSCBufferString]> json parser: Expected value while parsing array, attempting once more after unescaping...
Jun 17 09:20:06 sogod [3549]: [ERROR] <0x0x564519f381d0[GSCBufferString]> total failure. Original string is: {: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}
Jun 17 09:20:06 sogod [3549]: [ERROR] <0x0x5645197d9020[SOGoSQLUserProfile]> failure parsing json string: '{: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}'
Jun 17 09:20:06 sogod [3549]: [ERROR] <0x0x564519f381d0[GSCBufferString]> json parser: Expected value while parsing array, attempting once more after unescaping...
Jun 17 09:20:06 sogod [3549]: [ERROR] <0x0x564519f381d0[GSCBufferString]> total failure. Original string is: {: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}
Jun 17 09:20:06 sogod [3549]: [ERROR] <0x0x5645197d9020[SOGoSQLUserProfile]> failure parsing json string: '{: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}'
Jun 17 09:20:06 sogod [3549]: [ERROR] <0x0x564519f381d0[GSCBufferString]> json parser: Expected value while parsing array, attempting once more after unescaping...
Jun 17 09:20:06 sogod [3549]: [ERROR] <0x0x564519f381d0[GSCBufferString]> total failure. Original string is: {: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}
Jun 17 09:20:06 sogod [3549]: [ERROR] <0x0x5645197d9020[SOGoSQLUserProfile]> failure parsing json string: '{: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}'
Jun 17 09:20:06 sogod [17403]: [ERROR] <0x0x564517276380[GSCBufferString]> json parser: Expected value while parsing array, attempting once more after unescaping...
Jun 17 09:20:06 sogod [17403]: [ERROR] <0x0x564517276380[GSCBufferString]> total failure. Original string is: {: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}
Jun 17 09:20:06 sogod [17403]: [ERROR] <0x0x56451741ee40[SOGoSQLUserProfile]> failure parsing json string: '{: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}'
Jun 17 09:20:06 sogod [3549]: 212.201.104.11 "GET /SOGo/so/username/Calendar/eventslist?asc=1&day=20190617&filterpopup=view_today&search=title_Category_Location&sort=start&value= HTTP/1.1" 200 3473/0 0.033 - - 0
Jun 17 09:20:06 sogod [17403]: 212.201.104.11 "GET /SOGo/so/username/Calendar/eventsblocks?ed=20190623&sd=20190617&view=weekview HTTP/1.1" 200 17658/0 0.040 - - 700K
Jun 17 09:20:15 sogod [17403]: [ERROR] <0x0x564517aa4210[GSCBufferString]> json parser: Expected value while parsing array, attempting once more after unescaping...
Jun 17 09:20:15 sogod [17403]: [ERROR] <0x0x564517aa4210[GSCBufferString]> total failure. Original string is: {: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}
Jun 17 09:20:15 sogod [17403]: [ERROR] <0x0x5645174e3ac0[SOGoSQLUserProfile]> failure parsing json string: '{: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}'
Jun 17 09:20:15 sogod [17403]: <0x0x564517122a10[NGImap4Client]> TLS started successfully.
Jun 17 09:20:17 sogod [17403]: 212.201.104.11 "POST /SOGo/so/username/Mail/unseenCount HTTP/1.1" 200 157/150 1.596 - - 0
2019-06-17 09:20:20.925 sogod[17403:17403] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
Jun 17 09:20:21 sogod [17403]: <0x0x564517f0bcf0[NGImap4Client]> TLS started successfully.
Jun 17 09:20:21 sogod [17403]: 84.63.38.46 "POST /SOGo/so/drebelo/Mail/unseenCount HTTP/1.1" 200 21/31 1.032 - - 1M
2019-06-17 09:20:22.043 sogod[17403:17403] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
Jun 17 09:20:22 sogod [17403]: 192.168.3.10 "OPTIONS /SOGo/dav/avogel/ HTTP/1.1" 401 0/0 0.317 - - 0
2019-06-17 09:20:22.396 sogod[17403:17403] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
Jun 17 09:20:22 sogod [17403]: 192.168.3.10 "OPTIONS /SOGo/dav/avogel/ HTTP/1.1" 200 0/0 0.418 - - 1M
Jun 17 09:20:22 sogod [17403]: 192.168.3.10 "PROPFIND /SOGo/dav/avogel/Calendar/ HTTP/1.1" 401 0/2161 0.009 - - 0
Jun 17 09:20:23 sogod [17403]: 192.168.3.10 "PROPFIND /SOGo/dav/avogel/Calendar/ HTTP/1.1" 207 1816/2161 0.038 17357 89% 2M
2019-06-17 09:20:23.234 sogod[17403:17403] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
Jun 17 09:20:23 sogod [17403]: <0x0x564517824a70[NGImap4Client]> TLS started successfully.
Jun 17 09:20:24 sogod [17403]: 192.168.3.10 "POST /SOGo/so/lschiesl/Mail/0/folderINBOX/view HTTP/1.1" 200 12524/45 1.461 - - 84K
2019-06-17 09:20:27.853 sogod[17403:17403] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
2019-06-17 09:20:27.904 sogod[3549:3549] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
2019-06-17 09:20:28.179 sogod[3549:3549] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
2019-06-17 09:20:28.309 sogod[17403:17403] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
Jun 17 09:20:28 sogod [17403]: 192.168.3.10 "REPORT /SOGo/dav/staff-calendar/Calendar/personal/ HTTP/1.1" 207 125/188 0.566 - - 0
Jun 17 09:20:28 sogod [3549]: 192.168.3.10 "REPORT /SOGo/dav/rwuttke/Calendar/personal/ HTTP/1.1" 207 125/188 0.604 - - 0
2019-06-17 09:20:48.036 sogod[3549:3549] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
Jun 17 09:20:48 sogod [3549]: <0x0x56451ae40b30[NGImap4Client]> TLS started successfully.
Jun 17 09:20:48 sogod [3549]: 192.168.3.10 "POST /SOGo/so/lschiesl/Mail/unseenCount HTTP/1.1" 200 21/31 0.828 - - 0
2019-06-17 09:20:52.924 sogod[3549:3549] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
Jun 17 09:20:54 sogod [3549]: <0x0x56451b2254a0[NGImap4Client]> TLS started successfully.
Jun 17 09:20:54 sogod [3549]: 84.63.38.46 "POST /SOGo/so/drebelo/Mail/0/folderINBOX/view HTTP/1.1" 200 31241/45 1.707 - - 0
Jun 17 09:21:14 sogod [3549]: [ERROR] <0x0x56451b2bb0c0[GSCBufferString]> json parser: Expected value while parsing array, attempting once more after unescaping...
Jun 17 09:21:14 sogod [3549]: [ERROR] <0x0x56451b2bb0c0[GSCBufferString]> total failure. Original string is: {: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}
Jun 17 09:21:14 sogod [3549]: [ERROR] <0x0x56451a0fa650[SOGoSQLUserProfile]> failure parsing json string: '{: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}'
Jun 17 09:21:14 sogod [3549]: <0x0x5645193704b0[NGImap4Client]> TLS started successfully.
Jun 17 09:21:15 sogod [3549]: [ERROR] <0x0x56451b2bb0c0[GSCBufferString]> json parser: Expected value while parsing array, attempting once more after unescaping...
Jun 17 09:21:15 sogod [3549]: [ERROR] <0x0x56451b2bb0c0[GSCBufferString]> total failure. Original string is: {: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}
Jun 17 09:21:15 sogod [3549]: [ERROR] <0x0x56451a0fa650[SOGoSQLUserProfile]> failure parsing json string: '{: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}'
2019-06-17 09:21:15.417 sogod[3549:3549] parseTimeZone: cannot parse time notation 'UT'
Jun 17 09:21:15 sogod [3549]: 212.201.104.11 "POST /SOGo/so/username/Mail/0/foldershared/folderadministrator/folderpurchase/view HTTP/1.1" 200 30153/45 0.882 - - 0
Jun 17 09:21:21 sogod [3549]: <0x0x56451b09a150[NGImap4Client]> TLS started successfully.
Jun 17 09:21:21 sogod [3549]: 84.63.38.46 "POST /SOGo/so/drebelo/Mail/unseenCount HTTP/1.1" 200 21/31 0.473 - - 0
Jun 17 09:21:24 sogod [3549]: <0x0x56451add5220[NGImap4Client]> TLS started successfully.
Jun 17 09:21:24 sogod [3549]: 192.168.3.10 "POST /SOGo/so/lschiesl/Mail/0/folderINBOX/view HTTP/1.1" 200 12524/45 0.682 - - 0
Jun 17 09:21:48 sogod [3549]: <0x0x56451ab21970[NGImap4Client]> TLS started successfully.
Jun 17 09:21:48 sogod [3549]: 192.168.3.10 "POST /SOGo/so/lschiesl/Mail/unseenCount HTTP/1.1" 200 21/31 0.370 - - 0
2019-06-17 09:21:53.040 sogod[3549:3549] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
2019-06-17 09:21:53.257 sogod[3549:3549] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
2019-06-17 09:21:53.349 sogod[3549:3549] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
2019-06-17 09:21:53.458 sogod[3549:3549] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
Jun 17 09:21:53 sogod [3549]: <0x0x56451ad11a70[NGImap4Client]> TLS started successfully.
Jun 17 09:21:54 sogod [3549]: 84.63.38.46 "POST /SOGo/so/drebelo/Mail/0/folderINBOX/view HTTP/1.1" 200 31241/45 1.497 - - 0
Jun 17 09:22:06 sogod [3549]: [ERROR] <0x0x56451b19f930[GSCBufferString]> json parser: Expected value while parsing array, attempting once more after unescaping...
Jun 17 09:22:06 sogod [3549]: [ERROR] <0x0x56451b19f930[GSCBufferString]> total failure. Original string is: {: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}
Jun 17 09:22:06 sogod [3549]: [ERROR] <0x0x5645197b7dc0[SOGoSQLUserProfile]> failure parsing json string: '{: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}'
Jun 17 09:22:06 sogod [3549]: [ERROR] <0x0x56451b19f930[GSCBufferString]> json parser: Expected value while parsing array, attempting once more after unescaping...
Jun 17 09:22:06 sogod [3549]: [ERROR] <0x0x56451b19f930[GSCBufferString]> total failure. Original string is: {: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}
Jun 17 09:22:06 sogod [3549]: [ERROR] <0x0x5645197b7dc0[SOGoSQLUserProfile]> failure parsing json string: '{: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}'
Jun 17 09:22:06 sogod [3549]: [ERROR] <0x0x56451b19f930[GSCBufferString]> json parser: Expected value while parsing array, attempting once more after unescaping...
Jun 17 09:22:06 sogod [3549]: [ERROR] <0x0x56451b19f930[GSCBufferString]> total failure. Original string is: {: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}
Jun 17 09:22:06 sogod [3549]: [ERROR] <0x0x5645197b7dc0[SOGoSQLUserProfile]> failure parsing json string: '{: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}'
Jun 17 09:22:06 sogod [17403]: [ERROR] <0x0x564517953640[GSCBufferString]> json parser: Expected value while parsing array, attempting once more after unescaping...
Jun 17 09:22:06 sogod [17403]: [ERROR] <0x0x564517953640[GSCBufferString]> total failure. Original string is: {: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}
Jun 17 09:22:06 sogod [17403]: [ERROR] <0x0x564517963060[SOGoSQLUserProfile]> failure parsing json string: '{: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}'
Jun 17 09:22:06 sogod [3549]: 212.201.104.11 "GET /SOGo/so/username/Calendar/eventslist?asc=1&day=20190617&filterpopup=view_today&search=title_Category_Location&sort=start&value= HTTP/1.1" 200 3473/0 0.029 - - 0
Jun 17 09:22:06 sogod [17403]: 212.201.104.11 "GET /SOGo/so/username/Calendar/eventsblocks?ed=20190623&sd=20190617&view=weekview HTTP/1.1" 200 17658/0 0.039 - - 0
Jun 17 09:22:15 sogod [17403]: [ERROR] <0x0x56451792f480[GSCBufferString]> json parser: Expected value while parsing array, attempting once more after unescaping...
Jun 17 09:22:15 sogod [17403]: [ERROR] <0x0x56451792f480[GSCBufferString]> total failure. Original string is: {: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}
Jun 17 09:22:15 sogod [17403]: [ERROR] <0x0x564517939e70[SOGoSQLUserProfile]> failure parsing json string: '{: {: [, , , , , , , ], : , : {: , : , : , : , : , : , : }, : {: 0, : 0, : 0, : 0, : 0, : 0, : 0}, : {: 0}, : , : {: , : , : , : , : , : , : }, : , : {: 1, : 1, : 1, : 1, : 1, : 1, : 0}, : [, , , , , , ], : [], : {: 0}, : {: 0}, : {: 1}, : , : [, ]}, : {: [, ]}, : {: , : [], : {: }}, : , : {: [, , , , , ], : [, ]}}'
Jun 17 09:22:15 sogod [17403]: <0x0x564517257930[NGImap4Client]> TLS started successfully.
Jun 17 09:22:16 sogod [17403]: 212.201.104.11 "POST /SOGo/so/username/Mail/unseenCount HTTP/1.1" 200 157/150 0.749 - - 0
2019-06-17 09:22:21.703 sogod[17403:17403] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
2019-06-17 09:22:21.799 sogod[17403:17403] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
2019-06-17 09:22:21.894 sogod[17403:17403] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
2019-06-17 09:22:22.194 sogod[17403:17403] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
2019-06-17 09:22:22.421 sogod[17403:17403] Creating NGLdapConnection instance for bindDN 'cn=user,dc=ldap,dc=company,dc=com'
Jun 17 09:22:22 sogod [17403]: <0x0x564517f086f0[NGImap4Client]> TLS started successfully.
Jun 17 09:22:23 sogod [17403]: 84.63.38.46 "POST /SOGo/so/drebelo/Mail/unseenCount HTTP/1.1" 200 21/31 1.590 - - 0

sogo_error.txt (25,136 bytes)   
Christian Mack

Christian Mack

2019-06-18 09:59

developer   ~0013646

Can you dump the users account with sogo-tool?

ondrej.kolin

ondrej.kolin

2019-07-18 06:20

reporter   ~0013672

The users can log in and sogo creates a new profile for them, afaik. We can offer backuped profile from previous day, before the error occurred, are you interested in these?

Issue History

Date Modified Username Field Change
2019-06-17 07:56 ondrej.kolin New Issue
2019-06-17 07:56 ondrej.kolin File Added: sogo_error.txt
2019-06-18 09:59 Christian Mack Note Added: 0013646
2019-07-18 06:20 ondrej.kolin Note Added: 0013672