Re: [SOGo] Stable Installation doesn't work anymore

Mon, 03 Mar 2014 09:13:29 -0800 

Can you get in ssh now after reboot?

The mysql password is something  you configure when you create the database.

Are you using the zeg appliance or a sogo setup you built?
It looks like you have multiple issues. Go completely through the sogo installation pdf and look for what you have missed.
The database is populated only when a user successfully logs into the web interface so what happened to create duplicates in the database? 
Steve
On 3/3/2014 9:45 AM, Luca Egloff wrote:

Hi Steve
I listet the messages log file. The only abnormal were these Lines (It is the rest of the starting process):
Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 108: Duplicate column name 'File_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 114: Duplicate column name 'Grant_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 115: Duplicate column name 'Grant_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 116: Duplicate column name 'Grant_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 127: Duplicate column name 'ssl_type' Mar 3 15:55:32 lupra mysql.init: ERROR 1061 (42000) at line 138: Duplicate key name 'Grantor' Mar 3 15:55:32 lupra mysql.init: ERROR 1054 (42S22) at line 164: Unknown column 'Type' in 'columns_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 186: Duplicate column name 'type' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 196: Duplicate column name 'Show_db_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 213: Duplicate column name 'max_questions' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 223: Duplicate column name 'Create_tmp_table_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 226: Duplicate column name 'Create_tmp_table_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 320: Duplicate column name 'Create_view_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 323: Duplicate column name 'Create_view_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 326: Duplicate column name 'Create_view_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 332: Duplicate column name 'Show_view_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 335: Duplicate column name 'Show_view_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 338: Duplicate column name 'Show_view_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 355: Duplicate column name 'Create_routine_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 358: Duplicate column name 'Create_routine_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 361: Duplicate column name 'Create_routine_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 367: Duplicate column name 'Alter_routine_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 370: Duplicate column name 'Alter_routine_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 373: Duplicate column name 'Alter_routine_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 376: Duplicate column name 'Execute_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 379: Duplicate column name 'Execute_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 392: Duplicate column name 'max_user_connections' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 401: Duplicate column name 'Create_user_priv' Mar 3 15:55:32 lupra mysql.init: ERROR 1060 (42S21) at line 423: Duplicate column name 'Routine_type' 
Mar  3 15:55:33 lupra mysql.init: @hadGrantPriv:=1
Mar  3 15:55:33 lupra mysql.init: 1
Mar  3 15:55:33 lupra last message repeated 4 times
Mar  3 15:55:33 lupra mysql.init: @hadShowDbPriv:=1
Mar  3 15:55:33 lupra mysql.init: 1
Mar  3 15:55:33 lupra last message repeated 4 times
Mar  3 15:55:33 lupra mysql.init: @hadCreateViewPriv:=1
Mar  3 15:55:33 lupra mysql.init: 1
Mar  3 15:55:33 lupra last message repeated 4 times
Mar  3 15:55:33 lupra mysql.init: @hadCreateRoutinePriv:=1
Mar  3 15:55:33 lupra mysql.init: 1
Mar  3 15:55:33 lupra last message repeated 4 times
Mar  3 15:55:33 lupra mysql.init: @hadCreateUserPriv:=1
Mar  3 15:55:33 lupra mysql.init: 1
Mar  3 15:55:33 lupra last message repeated 4 times
Mar  3 15:55:33 lupra mysql.init: waiting for mysqld to restart
Mar  3 15:55:33 lupra noip2[3992]: supra….  was already set to 85….
Mar  3 15:55:35 lupra last message repeated 2 times
Mar  3 15:55:38 lupra esmith::event[4151]: Processing event: local
Mar 3 15:55:38 lupra esmith::event[4151]: Running event handler: /etc/e-smith/events/actions/generic_template_expand Mar 3 15:55:38 lupra esmith::event[4151]: expanding /etc/sysconfig/kernel 
Mar  3 15:55:38 lupra esmith::event[4151]: expanding /boot/grub/grub.conf
Mar 3 15:55:38 lupra esmith::event[4151]: generic_template_expand=action|Event|local|Action|generic_template_expand|Start|1393858538 153826|End|1393858538 665951|Elapsed|0.512125 Mar 3 15:55:38 lupra esmith::event[4151]: Running event handler: /etc/e-smith/events/local/S50clear-pptp-interfaces Mar 3 15:55:38 lupra esmith::event[4151]: S50clear-pptp-interfaces=action|Event|local|Action|S50clear-pptp-interfaces|Start|1393858538 666213|End|1393858538 726987|Elapsed|0.060774 Mar 3 15:55:38 lupra esmith::event[4151]: Running event handler: /etc/e-smith/events/actions/adjust-services Mar 3 15:55:38 lupra esmith::event[4151]: adjusting supervised yum (once) Mar 3 15:55:38 lupra esmith::event[4151]: adjust-services=action|Event|local|Action|adjust-services|Start|1393858538 727276|End|1393858538 810183|Elapsed|0.082907
Shall I manipulate the mysql DB? What is normally the password for mysql in the SOGo Installation? 


Thanks in advance
Luca
Am 03.03.2014 um 15:41 schrieb Steve Boley <[email protected] <mailto:[email protected]>>:
Reboot your linux server as the network stack is having some serious issues and it would be easiest to see if a server reboot would fix the problem and then start looking at the logs and see what is wrong. 
Steve
On 3/2/2014 11:35 PM, Luca Egloff wrote:

Hi Steve

In the sshd-log it says:
Received signal 15; terminating.

Each time I try to log in.
What could this be?

What do you mean with reboot the box?


Greetings
Luca
Am 28.02.2014 um 20:16 schrieb Steve Boley <[email protected] <mailto:[email protected]>>:
If you can't login ssh then something has seriously gone wrong with the network stack and the box needs to be rebooted for sure. 
Steve
On 2/28/2014 8:41 AM, Luca Egloff wrote:

Hi
I have an SME Server running (8.0). On this server I installed SOGo . Alls worked fine for several months. Now I can no more access the SOGo Interface. Neither can I log in to the Server from another Machine via Web-Browser or SSH 
(Terminal).
I hope someone can help me. I post the Output of the Sogo-Log:
Feb 28 11:26:21 sogod [3509]: <0x0x9f3c76c[WOWatchDog]> Terminating with 
SIGINT or SIGTERM
Feb 28 11:26:21 sogod [3509]: <0x0x9d9a6ec[WOWatchDogChild]> sending terminate 
signal to pid 3616
Feb 28 11:26:21 sogod [3509]: <0x0x9f844bc[WOWatchDogChild]> sending terminate 
signal to pid 3615
Feb 28 11:26:21 sogod [3509]: <0x0x9d934ec[WOWatchDogChild]> sending terminate 
signal to pid 3614
Feb 28 11:26:21 sogod [3509]: <0x0x9f3c76c[WOWatchDog]> Terminating with 
SIGINT or SIGTERM
Feb 28 11:26:21 sogod [3509]: <0x0x9f844bc[WOWatchDogChild]> child 3615 exited Feb 28 11:26:21 sogod [3509]: <0x0x9f3c76c[WOWatchDog]> Terminating with 
SIGINT or SIGTERM
Feb 28 11:26:21 sogod [3509]: <0x0x9d934ec[WOWatchDogChild]> child 3614 exited Feb 28 11:26:21 sogod [3509]: <0x0x9f3c76c[WOWatchDog]> Terminating with 
SIGINT or SIGTERM
Feb 28 11:26:21 sogod [3509]: <0x0x9d9a6ec[WOWatchDogChild]> child 3616 exited Feb 28 11:26:21 sogod [3509]: <0x0x9f3c76c[WOWatchDog]> all children exited. 
We now terminate.
Feb 28 11:26:22 sogod [4189]: version 2.2.0 (build [email protected] <mailto:[email protected]> 
201402241708) -- starting
Feb 28 11:26:22 sogod [4189]: vmem size check enabled: shutting down app when 
vmem > 384 MB
Feb 28 11:26:22 sogod [4189]: <0x0x836aa6c[SOGoProductLoader]> SOGo products 
loaded from '/usr/lib/GNUstep/SOGo':
Feb 28 11:26:22 sogod [4189]: <0x0x836aa6c[SOGoProductLoader]>
PreferencesUI.SOGo, MainUI.SOGo, MailerUI.SOGo, CommonUI.SOGo, Contacts.SOGo, 
AdministrationUI.SOGo, ContactsUI.SOGo, Appointments.SOGo,
MailPartViewers.SOGo, Mailer.SOGo, SchedulerUI.SOGo
Feb 28 11:26:22 sogod [4189]: <0x0x842a9fc[WOWatchDog]> listening on
127.0.0.1:20000
Feb 28 11:26:22 sogod [4189]: <0x0x842a9fc[WOWatchDog]> watchdog process pid: 
4189
Feb 28 11:26:22 sogod [4189]: <0x0x75aba0[WOWatchDogChild]> watchdog request 
timeout set to 10 minutes
Feb 28 11:26:22 sogod [4189]: <0x0x842a9fc[WOWatchDog]> preparing 3 children Feb 28 11:26:22 sogod [4189]: <0x0x842a9fc[WOWatchDog]> child spawned with pid 
4190
Feb 28 11:26:22 sogod [4189]: <0x0x842a9fc[WOWatchDog]> child spawned with pid 
4191
Feb 28 11:26:22 sogod [4189]: <0x0x842a9fc[WOWatchDog]> child spawned with pid 
4192
Feb 28 12:13:41 sogod [4192]: <0x0x82a33b4[SOGoCache]> Cache cleanup interval 
set every 300.000000 seconds
Feb 28 12:13:41 sogod [4192]: <0x0x82a33b4[SOGoCache]> Using host(s)
'localhost' as server(s)
178.197.237.21 - - [28/Feb/2014:12:13:41 GMT] "OPTIONS /SOGo/dav/claudia/ 
HTTP/1.1" 401 0/0 0.042 - - 1M
Feb 28 12:13:41 sogod [4192]: <0x0x826ae74[LDAPSource]> <NSException:
0x85e2e04> NAME:LDAPException REASON:operation bind failed: Invalid
credentials (0x31) INFO:{login = "cn=claudia,ou=groups,dc=lupra,dc=no-
ip,dc=org"; }
178.197.237.21 - - [28/Feb/2014:12:13:41 GMT] "OPTIONS /SOGo/dav/claudia/ 
HTTP/1.1" 200 0/0 0.013 - - 576K
Feb 28 12:13:42 sogod [4192]: [WARN] <0x0x82a33a4[SOGoWebDAVAclManager]> entry 
'{DAV:}write' already exists in DAV permissions table
Feb 28 12:13:42 sogod [4192]: [WARN] <0x0x82a33a4[SOGoWebDAVAclManager]> entry 
'{DAV:}write-properties' already exists in DAV permissions table
Feb 28 12:13:42 sogod [4192]: [WARN] <0x0x82a33a4[SOGoWebDAVAclManager]> entry 
'{DAV:}write-content' already exists in DAV permissions table
2014-02-28 12:13:42.106 sogod[4192] Note(SoObject): SoDebugKeyLookup is 
enabled!
2014-02-28 12:13:42.107 sogod[4192] Note(SoObject): SoDebugBaseURL is enabled! 2014-02-28 12:13:42.107 sogod[4192] Note(SoObject): relative base URLs are 
enabled.
2014-02-28 12:13:42.118 sogod[4192] File NSKeyValueCoding.m: 913. In -
[NSObject(KeyValueCoding) valuesForKeys:] This method is deprecated, use - 
dictionaryWithValuesForKeys:
178.197.237.21 - - [28/Feb/2014:12:13:42 GMT] "PROPFIND /SOGo/dav/claudia/ 
Calendar/ HTTP/1.1" 207 1394/2037 0.119 9264 84% 1M
188.63.53.242 - - [28/Feb/2014:13:07:44 GMT] "OPTIONS /SOGo/dav/claudia/ 
HTTP/1.1" 401 0/0 0.007 - - 0
Feb 28 13:07:44 sogod [4192]: <0x0x826ae74[LDAPSource]> <NSException:
0x85f6c4c> NAME:LDAPException REASON:operation bind failed: Invalid
credentials (0x31) INFO:{login = "cn=claudia,ou=groups,dc=lupra,dc=no-
ip,dc=org"; }
188.63.53.242 - - [28/Feb/2014:13:07:44 GMT] "OPTIONS /SOGo/dav/claudia/ 
HTTP/1.1" 200 0/0 0.007 - - 0
188.63.53.242 - - [28/Feb/2014:13:07:44 GMT] "PROPFIND /SOGo/dav/claudia/ 
Calendar/ HTTP/1.1" 207 1394/2037 0.069 9264 84% 72K
178.197.237.53 - - [28/Feb/2014:13:07:55 GMT] "PROPFIND /SOGo/dav/luca/ 
Calendar/ HTTP/1.1" 401 0/2097 0.384 - - 0
Feb 28 13:07:58 sogod [4192]: <0x0x826ae74[LDAPSource]> <NSException:
0x859483c> NAME:LDAPException REASON:operation bind failed: Invalid
credentials (0x31) INFO:{login = "cn=luca,ou=groups,dc=lupra,dc=no-
ip,dc=org"; }
178.197.237.53 - - [28/Feb/2014:13:07:58 GMT] "PROPFIND /SOGo/dav/luca/ 
Calendar/ HTTP/1.1" 207 1391/2097 0.649 9230 84% 4K
178.197.237.53 - - [28/Feb/2014:13:07:59 GMT] "PROPFIND /SOGo/dav/luca/ 
Calendar/claudia_personal/ HTTP/1.1" 207 319/181 0.092 - - 0
2014-02-28 13:08:00.090 sogod[4192] WOCompoundElement: pool embedding is on. 2014-02-28 13:08:00.090 sogod[4192] WOCompoundElement: id logging is on. 178.197.237.53 - - [28/Feb/2014:13:08:00 GMT] "PUT /SOGo/dav/luca/Calendar/ personal/99128131-DC15-473C-A837-DD661CED04A8.ics HTTP/1.1" 201 0/918 0.368 - 
- 1M
178.197.237.53 - - [28/Feb/2014:13:08:00 GMT] "PROPFIND /SOGo/dav/luca/ 
Calendar/claudia_personal/ HTTP/1.1" 207 2530/145 0.189 23805 89% 132K
178.197.237.53 - - [28/Feb/2014:13:08:01 GMT] "PROPFIND /SOGo/dav/luca/ 
Calendar/personal/ HTTP/1.1" 207 311/181 0.091 - - 0
Feb 28 13:08:09 sogod [4192]: [ERROR]
<0x086904D4[SOGoAppointmentFolder]:claudia_personal> DAV property
'{http://calendarserver.org/ns/}created-by' has no matching SQL field,
response could be incomplete
Feb 28 13:08:09 sogod [4192]: [ERROR]
<0x086904D4[SOGoAppointmentFolder]:claudia_personal> DAV property
'{http://calendarserver.org/ns/}updated-by' has no matching SQL field,
response could be incomplete
Feb 28 13:08:09 sogod [4192]: [ERROR]
<0x086904D4[SOGoAppointmentFolder]:claudia_personal> DAV property
'{urn:ietf:params:xml:ns:caldav}schedule-tag' has no matching SQL field, 
response could be incomplete
178.197.237.53 - - [28/Feb/2014:13:08:09 GMT] "REPORT /SOGo/dav/luca/Calendar/ 
claudia_personal/ HTTP/1.1" 207 1097/924 7.243 5161 78% 500K
178.197.237.53 - - [28/Feb/2014:13:08:14 GMT] "PROPFIND /SOGo/dav/luca/ 
Calendar/personal/ HTTP/1.1" 207 428/145 0.070 1779 75% -292K
178.197.237.53 - - [28/Feb/2014:13:08:53 GMT] "OPTIONS /SOGo/dav/luca/
HTTP/1.1" 401 0/0 0.002 - - 0
178.197.237.53 - - [28/Feb/2014:13:08:55 GMT] "OPTIONS /SOGo/dav/luca/
HTTP/1.1" 200 0/0 0.003 - - 0
178.197.237.53 - - [28/Feb/2014:13:08:56 GMT] "PROPFIND /SOGo/dav/luca/ 
Calendar/ HTTP/1.1" 207 1390/2097 0.788 9230 84% 0
178.197.237.21 - - [28/Feb/2014:14:03:34 GMT] "OPTIONS /SOGo/dav/claudia/ 
HTTP/1.1" 401 0/0 0.007 - - 0
Feb 28 14:03:35 sogod [4192]: <0x0x826ae74[LDAPSource]> <NSException:
0x8675494> NAME:LDAPException REASON:operation bind failed: Invalid
credentials (0x31) INFO:{login = "cn=claudia,ou=groups,dc=lupra,dc=no-
ip,dc=org"; }
178.197.237.21 - - [28/Feb/2014:14:03:35 GMT] "OPTIONS /SOGo/dav/claudia/ 
HTTP/1.1" 200 0/0 0.006 - - 0
188.63.53.242 - - [28/Feb/2014:14:03:37 GMT] "PROPFIND /SOGo/dav/claudia/ 
Calendar/ HTTP/1.1" 207 1393/2037 0.116 9264 84% 0
188.63.53.242 - - [28/Feb/2014:14:03:37 GMT] "PROPFIND /SOGo/dav/claudia/ 
Calendar/luca_personal/ HTTP/1.1" 207 319/181 0.011 - - 0
188.63.53.242 - - [28/Feb/2014:14:03:37 GMT] "PROPFIND /SOGo/dav/claudia/ 
Calendar/luca_personal/ HTTP/1.1" 207 435/145 0.011 1827 76% 0
Feb 28 14:03:37 sogod [4192]: [ERROR]
<0x085AB594[SOGoAppointmentFolder]:luca_personal> DAV property
'{http://calendarserver.org/ns/}updated-by' has no matching SQL field,
response could be incomplete
Feb 28 14:03:37 sogod [4192]: [ERROR]
<0x085AB594[SOGoAppointmentFolder]:luca_personal> DAV property
'{urn:ietf:params:xml:ns:caldav}schedule-tag' has no matching SQL field, 
response could be incomplete
Feb 28 14:03:37 sogod [4192]: [ERROR]
<0x085AB594[SOGoAppointmentFolder]:luca_personal> DAV property
'{http://calendarserver.org/ns/}created-by' has no matching SQL field,
response could be incomplete
188.63.53.242 - - [28/Feb/2014:14:03:37 GMT] "REPORT /SOGo/dav/claudia/ 
Calendar/luca_personal/ HTTP/1.1" 207 646/460 0.032 1139 43% 0

Thanks in advance
Greetings
Luca


--
[email protected] <mailto:[email protected]>
https://inverse.ca/sogo/lists









--
[email protected]
https://inverse.ca/sogo/lists

Reply via email to