[OBMFULL-5966] obm-satellite error when restart and automaton fails Created: 16/May/14  Updated: 19/May/14  Resolved: 19/May/14

Status: Closed
Project: OBM
Component/s: automate
Affects Version/s: 3.0.0
Fix Version/s: 3.0.0

Type: Bug Priority: Blocker
Reporter: Stephane COLSON Assignee: Emmanuel Surleau
Resolution: Fixed Votes: 0
Labels: None
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified
Environment:

OBM 3.0.0~0.alpha0+git20140516-1140-3428720


Similar Issues: Show 9 results 
Rank: 6191

 Description   

After a fresh install of obm-full on Wheezy with version 3.0.0~0.alpha0+git20140516-1140-3428720, automaton (yellow chip) fails because apparently obm-satellite is not correctly started.

When restarting obm-satellite, here are the logs:

obm-satelitte logs when restart
2014/05/16 16:46:16:918364 [26514]: ObmSatellite::Server:190 INFO - Daemon stopped by TERM
2014/05/16 16:46:16:918362 [26453]: ObmSatellite::Server:108 WARN - Daemon stopped by TERM
2014/05/16 16:46:16:918423 [26513]: ObmSatellite::Server:190 INFO - Daemon stopped by TERM
2014/05/16 16:46:19:6 [32734]: ObmSatellite::Server:67 INFO - Parent daemon running (PID: 32734)
2014/05/16 16:46:20:402 [32734]: ObmSatellite::Server::configure:202 INFO - needed LDAP service initialized
2014/05/16 16:46:21:1939 [32734]: ObmSatellite::Services::LDAP:242 ERROR - no TLS/SSL LDAP server support
2014/05/16 16:46:21:1939 [32734]: ObmSatellite::Services::LDAP:282 INFO - Authenticating to LDAP server 'ldap://127.0.0.1/' as user DN uid=obmsatellite,ou=sysusers,dc=local
2014/05/16 16:46:21:2186 [32734]: ObmSatellite::Services::LDAP:303 ERROR - fail to authenticate against LDAP server : Invalid credentials
2014/05/16 16:46:21:2187 [32734]: ObmSatellite::Services::CYRUS:112 FATAL - Can't get 'cyrus' admin password from LDAP
2014/05/16 16:46:21:2188 [32734]: ObmSatellite::Services::CYRUS:54 FATAL - Can't get Cyrus admin 'cyrus' password from LDAP
2014/05/16 16:46:21:2188 [32734]: ObmSatellite::Server::configure:199 ERROR - unable to initialize needed CYRUS service
2014/05/16 16:46:21:2188 [32734]: ObmSatellite::Server::module:63 ERROR - starting needed service failed. Loading module 'backupEntity' failed
2014/05/16 16:46:21:2214 [32734]: ObmSatellite::Server::module:73 INFO - loading module 'cyrusPartition' success
2014/05/16 16:46:21:2247 [32734]: ObmSatellite::Server::module:73 INFO - loading module 'postfixAccess' success
2014/05/16 16:46:21:2270 [32734]: ObmSatellite::Server::module:73 INFO - loading module 'postfixSmtpInMaps' success
2014/05/16 16:46:21:2270 [32734]: ObmSatellite::Server:220 INFO - Trying to bind to SSL port 30000 using certificate /etc/obm/certs/obm_cert.pem (key /etc/obm/certs/obm_cert.pem)
2014/05/16 16:46:21:2271 [32734]: ObmSatellite::Server:222 INFO - CA certificate /var/lib/obm-ca/cacert.pem
2014/05/16 16:46:22:2527 [32734]: ObmSatellite::Server:169 INFO - new child forked (PID: 32755), we now have 1 child
2014/05/16 16:46:22:2529 [32734]: ObmSatellite::Server:169 INFO - new child forked (PID: 32756), we now have 2 child
2014/05/16 16:46:22:2530 [32755]: ObmSatellite::Server:175 INFO - child 32755 ready to process requests
2014/05/16 16:46:22:2534 [32756]: ObmSatellite::Server:175 INFO - child 32756 ready to process requests

And if I create a host and a domain, the update with the yellow chip never ends. I infinitely have remaining updates. I'm stuck with it.



 Comments   
Comment by David Dolcimascolo [ 19/May/14 ]

Fresh install of 3.0.0~0.alpha0+git20140516-1140-3428720, the automaton and satellite services are fine. I can create my host, domain and first user without issues. I do however have the same errors as you in my logs.

Comment by David Dolcimascolo [ 19/May/14 ]
root@whmy:~# tail -50f /var/log/obm-satellite/obmSatellite.log 
2014/05/19 09:37:27:5 [4231]: ObmSatellite::Server:67 INFO - Parent daemon running (PID: 4231)
2014/05/19 09:37:27:101 [4231]: ObmSatellite::Server::configure:202 INFO - needed LDAP service initialized
2014/05/19 09:37:27:101 [4231]: ObmSatellite::Server::module:78 FATAL - No module loaded !
2014/05/19 09:37:27:101 [4231]: ObmSatellite::Server::configure:78 FATAL - Modules initialization fail
2014/05/19 09:39:03:7 [23698]: ObmSatellite::Server:67 INFO - Parent daemon running (PID: 23698)
2014/05/19 09:39:03:149 [23698]: ObmSatellite::Server::configure:202 INFO - needed LDAP service initialized
2014/05/19 09:39:04:490 [23698]: ObmSatellite::Services::LDAP:242 ERROR - no TLS/SSL LDAP server support
2014/05/19 09:39:04:490 [23698]: ObmSatellite::Services::LDAP:282 INFO - Authenticating to LDAP server 'ldap://127.0.0.1/' as user DN uid=obmsatellite,ou=sysusers,dc=local
2014/05/19 09:39:04:531 [23698]: ObmSatellite::Services::LDAP:303 ERROR - fail to authenticate against LDAP server : Invalid credentials
2014/05/19 09:39:04:533 [23698]: ObmSatellite::Services::CYRUS:112 FATAL - Can't get 'cyrus' admin password from LDAP
2014/05/19 09:39:04:533 [23698]: ObmSatellite::Services::CYRUS:54 FATAL - Can't get Cyrus admin 'cyrus' password from LDAP
2014/05/19 09:39:04:534 [23698]: ObmSatellite::Server::configure:199 ERROR - unable to initialize needed CYRUS service
2014/05/19 09:39:04:534 [23698]: ObmSatellite::Server::module:63 ERROR - starting needed service failed. Loading module 'backupEntity' failed
2014/05/19 09:39:04:540 [23698]: ObmSatellite::Server::module:73 INFO - loading module 'cyrusPartition' success
2014/05/19 09:39:04:541 [23698]: ObmSatellite::Server:220 INFO - Trying to bind to SSL port 30000 using certificate /etc/obm/certs/obm_cert.pem (key /etc/obm/certs/obm_cert.pem)
2014/05/19 09:39:04:541 [23698]: ObmSatellite::Server:222 INFO - CA certificate /var/lib/obm-ca/cacert.pem
2014/05/19 09:39:04:754 [23698]: ObmSatellite::Server:169 INFO - new child forked (PID: 23736), we now have 1 child
2014/05/19 09:39:04:755 [23698]: ObmSatellite::Server:169 INFO - new child forked (PID: 23737), we now have 2 child
2014/05/19 09:39:04:761 [23737]: ObmSatellite::Server:175 INFO - child 23737 ready to process requests
2014/05/19 09:39:04:763 [23736]: ObmSatellite::Server:175 INFO - child 23736 ready to process requests
2014/05/19 09:39:07:3412 [23698]: ObmSatellite::Server:108 WARN - Daemon stopped by TERM
2014/05/19 09:39:07:3416 [23736]: ObmSatellite::Server:190 INFO - Daemon stopped by TERM
2014/05/19 09:39:07:3420 [23737]: ObmSatellite::Server:190 INFO - Daemon stopped by TERM
2014/05/19 09:39:07:7 [24007]: ObmSatellite::Server:67 INFO - Parent daemon running (PID: 24007)
2014/05/19 09:39:07:129 [24007]: ObmSatellite::Server::configure:202 INFO - needed LDAP service initialized
2014/05/19 09:39:07:264 [24007]: ObmSatellite::Services::LDAP:242 ERROR - no TLS/SSL LDAP server support
2014/05/19 09:39:07:264 [24007]: ObmSatellite::Services::LDAP:282 INFO - Authenticating to LDAP server 'ldap://127.0.0.1/' as user DN uid=obmsatellite,ou=sysusers,dc=local
2014/05/19 09:39:07:267 [24007]: ObmSatellite::Services::LDAP:303 ERROR - fail to authenticate against LDAP server : Invalid credentials
2014/05/19 09:39:07:268 [24007]: ObmSatellite::Services::CYRUS:112 FATAL - Can't get 'cyrus' admin password from LDAP
2014/05/19 09:39:07:268 [24007]: ObmSatellite::Services::CYRUS:54 FATAL - Can't get Cyrus admin 'cyrus' password from LDAP
2014/05/19 09:39:07:268 [24007]: ObmSatellite::Server::configure:199 ERROR - unable to initialize needed CYRUS service
2014/05/19 09:39:07:268 [24007]: ObmSatellite::Server::module:63 ERROR - starting needed service failed. Loading module 'backupEntity' failed
2014/05/19 09:39:07:271 [24007]: ObmSatellite::Server::module:73 INFO - loading module 'cyrusPartition' success
2014/05/19 09:39:07:276 [24007]: ObmSatellite::Server::module:73 INFO - loading module 'postfixAccess' success
2014/05/19 09:39:07:300 [24007]: ObmSatellite::Server::module:73 INFO - loading module 'postfixSmtpInMaps' success
2014/05/19 09:39:07:300 [24007]: ObmSatellite::Server:220 INFO - Trying to bind to SSL port 30000 using certificate /etc/obm/certs/obm_cert.pem (key /etc/obm/certs/obm_cert.pem)
2014/05/19 09:39:07:300 [24007]: ObmSatellite::Server:222 INFO - CA certificate /var/lib/obm-ca/cacert.pem
2014/05/19 09:39:07:366 [24007]: ObmSatellite::Server:169 INFO - new child forked (PID: 24051), we now have 1 child
2014/05/19 09:39:07:367 [24007]: ObmSatellite::Server:169 INFO - new child forked (PID: 24052), we now have 2 child
2014/05/19 09:39:07:370 [24052]: ObmSatellite::Server:175 INFO - child 24052 ready to process requests
2014/05/19 09:39:07:372 [24051]: ObmSatellite::Server:175 INFO - child 24051 ready to process requests


2014/05/19 09:47:37:509940 [24051]: ObmSatellite::Server::processRequest:31 INFO - Connect from: 127.0.0.1
2014/05/19 09:47:37:510023 [24051]: ObmSatellite::Services::LDAP:242 ERROR - no TLS/SSL LDAP server support
2014/05/19 09:47:37:510028 [24051]: ObmSatellite::Services::LDAP:282 INFO - Authenticating to LDAP server 'ldap://127.0.0.1/' as user DN uid=obmsatellite,ou=sysusers,dc=local
2014/05/19 09:47:37:510044 [24051]: ObmSatellite::Services::LDAP:293 INFO - LDAP connection success
2014/05/19 09:47:37:510048 [24051]: ObmSatellite::Services::LDAP:326 INFO - LDAP authentication success for user uid=obmsatelliterequest,ou=sysusers,dc=local
2014/05/19 09:47:37:510049 [24051]: ObmSatellite::Services::LDAP:282 INFO - Authenticating to LDAP server 'ldap://127.0.0.1/' as user DN uid=obmsatellite,ou=sysusers,dc=local
2014/05/19 09:47:37:510051 [24051]: ObmSatellite::Services::LDAP:293 INFO - LDAP connection success
2014/05/19 09:47:37:510054 [24051]: ObmSatellite::Server::module:139 INFO - Sending request '/postfixsmtpinmaps/host/Local' to module 'postfixSmtpInMaps'
2014/05/19 09:47:37:510063 [24051]: ObmSatellite::Modules::postfixSmtpInMaps:117 INFO - Generate flat map 'mailboxMap', file /etc/postfix/virtual_mailbox
2014/05/19 09:47:37:510077 [24051]: ObmSatellite::Modules::postfixSmtpInMaps:117 INFO - Generate flat map 'aliasMap', file /etc/postfix/virtual_alias
2014/05/19 09:47:37:510079 [24051]: ObmSatellite::Modules::postfixSmtpInMaps:117 INFO - Generate flat map 'transportMap', file /etc/postfix/transport
2014/05/19 09:47:37:510088 [24051]: ObmSatellite::Modules::postfixSmtpInMaps:117 INFO - Generate flat map 'domainMap', file /etc/postfix/virtual_domains
2014/05/19 09:47:37:510091 [24051]: ObmSatellite::Modules::postfixSmtpInMaps:125 INFO - Generate DB map 'mailboxMap', from file /etc/postfix/virtual_mailbox
2014/05/19 09:47:37:510141 [24051]: ObmSatellite::Modules::postfixSmtpInMaps:125 INFO - Generate DB map 'aliasMap', from file /etc/postfix/virtual_alias
2014/05/19 09:47:37:510159 [24051]: ObmSatellite::Modules::postfixSmtpInMaps:125 INFO - Generate DB map 'transportMap', from file /etc/postfix/transport
2014/05/19 09:47:37:510175 [24051]: ObmSatellite::Modules::postfixSmtpInMaps:125 INFO - Generate DB map 'domainMap', from file /etc/postfix/virtual_domains
2014/05/19 09:47:37:510219 [24051]: ObmSatellite::Server::processRequest:52 INFO - Disconnect from: 127.0.0.1
2014/05/19 09:48:26:559353 [24052]: ObmSatellite::Server::processRequest:31 INFO - Connect from: 127.0.0.1
2014/05/19 09:48:26:559388 [24052]: ObmSatellite::Services::LDAP:242 ERROR - no TLS/SSL LDAP server support
2014/05/19 09:48:26:559389 [24052]: ObmSatellite::Services::LDAP:282 INFO - Authenticating to LDAP server 'ldap://127.0.0.1/' as user DN uid=obmsatellite,ou=sysusers,dc=local
2014/05/19 09:48:26:559390 [24052]: ObmSatellite::Services::LDAP:293 INFO - LDAP connection success
2014/05/19 09:48:26:559392 [24052]: ObmSatellite::Services::LDAP:326 INFO - LDAP authentication success for user uid=obmsatelliterequest,ou=sysusers,dc=local
2014/05/19 09:48:26:559393 [24052]: ObmSatellite::Services::LDAP:282 INFO - Authenticating to LDAP server 'ldap://127.0.0.1/' as user DN uid=obmsatellite,ou=sysusers,dc=local
2014/05/19 09:48:26:559394 [24052]: ObmSatellite::Services::LDAP:293 INFO - LDAP connection success
2014/05/19 09:48:26:559397 [24052]: ObmSatellite::Server::module:139 INFO - Sending request '/cyruspartition/host/add/Local' to module 'cyrusPartition'
2014/05/19 09:48:26:559403 [24052]: ObmSatellite::Modules::cyrusPartition:194 INFO - Default partition name 'default'
2014/05/19 09:48:26:559404 [24052]: ObmSatellite::Modules::cyrusPartition:227 INFO - Add Cyrus Imapd partition 'obm_pg', directory '/var/spool/cyrus/obm_pg'
2014/05/19 09:48:26:559404 [24052]: ObmSatellite::Modules::cyrusPartition:231 INFO - Re-write Cyrus Imapd configuration file
2014/05/19 09:48:26:559405 [24052]: ObmSatellite::Modules::cyrusPartition:267 INFO - Checking special commands of Cyrus init service /etc/init.d/cyrus-imapd help | grep -q quickstart >/dev/null 2>&1
2014/05/19 09:48:26:559476 [24052]: ObmSatellite::Modules::cyrusPartition:276 INFO - Stop Cyrus service /etc/init.d/cyrus-imapd stop > /dev/null 2>&1
2014/05/19 09:48:26:559568 [24052]: ObmSatellite::Modules::cyrusPartition:285 INFO - Start Cyrus service /etc/init.d/cyrus-imapd start > /dev/null 2>&1
2014/05/19 09:48:27:559812 [24052]: ObmSatellite::Server::processRequest:52 INFO - Disconnect from: 127.0.0.1
2014/05/19 09:48:28:561608 [24052]: ObmSatellite::Server::processRequest:31 INFO - Connect from: 127.0.0.1
2014/05/19 09:48:28:561610 [24052]: ObmSatellite::Server::module:139 INFO - Sending request '/postfixsmtpinmaps/host/Local' to module 'postfixSmtpInMaps'
2014/05/19 09:48:28:561614 [24052]: ObmSatellite::Modules::postfixSmtpInMaps:117 INFO - Generate flat map 'mailboxMap', file /etc/postfix/virtual_mailbox
2014/05/19 09:48:28:561617 [24052]: ObmSatellite::Modules::postfixSmtpInMaps:117 INFO - Generate flat map 'aliasMap', file /etc/postfix/virtual_alias
2014/05/19 09:48:28:561619 [24052]: ObmSatellite::Modules::postfixSmtpInMaps:117 INFO - Generate flat map 'transportMap', file /etc/postfix/transport
2014/05/19 09:48:28:561623 [24052]: ObmSatellite::Modules::postfixSmtpInMaps:117 INFO - Generate flat map 'domainMap', file /etc/postfix/virtual_domains
2014/05/19 09:48:28:561625 [24052]: ObmSatellite::Modules::postfixSmtpInMaps:125 INFO - Generate DB map 'mailboxMap', from file /etc/postfix/virtual_mailbox
2014/05/19 09:48:28:561651 [24052]: ObmSatellite::Modules::postfixSmtpInMaps:125 INFO - Generate DB map 'aliasMap', from file /etc/postfix/virtual_alias
2014/05/19 09:48:28:561678 [24052]: ObmSatellite::Modules::postfixSmtpInMaps:125 INFO - Generate DB map 'transportMap', from file /etc/postfix/transport
2014/05/19 09:48:29:561725 [24052]: ObmSatellite::Modules::postfixSmtpInMaps:125 INFO - Generate DB map 'domainMap', from file /etc/postfix/virtual_domains
2014/05/19 09:48:29:561759 [24052]: ObmSatellite::Server::processRequest:52 INFO - Disconnect from: 127.0.0.1
Comment by David Dolcimascolo [ 19/May/14 ]

Restarting the service afterwards exhibits no errors. I guess this is because the OBM LDAP isn't created after a fresh install, but only when the automaton runs for the first time.

root@whmy:~# /etc/init.d/obm-satellite restart
[....] Restarting obm-satellite service: init-obmsatellite
2014/05/19 09:50:12:664868 [24007]: ObmSatellite::Server:108 WARN - Daemon stopped by TERM
2014/05/19 09:50:12:664870 [24051]: ObmSatellite::Server:190 INFO - Daemon stopped by TERM
2014/05/19 09:50:12:664871 [24052]: ObmSatellite::Server:190 INFO - Daemon stopped by TERM
2014/05/19 09:50:12:16 [32354]: ObmSatellite::Server:67 INFO - Parent daemon running (PID: 32354)
. ok 
root@whmy:~# 2014/05/19 09:50:12:101 [32354]: ObmSatellite::Server::configure:202 INFO - needed LDAP service initialized
2014/05/19 09:50:12:202 [32354]: ObmSatellite::Services::LDAP:242 ERROR - no TLS/SSL LDAP server support
2014/05/19 09:50:12:202 [32354]: ObmSatellite::Services::LDAP:282 INFO - Authenticating to LDAP server 'ldap://127.0.0.1/' as user DN uid=obmsatellite,ou=sysusers,dc=local
2014/05/19 09:50:12:204 [32354]: ObmSatellite::Services::LDAP:293 INFO - LDAP connection success
2014/05/19 09:50:12:206 [32354]: ObmSatellite::Server::configure:202 INFO - needed CYRUS service initialized
2014/05/19 09:50:12:206 [32354]: ObmSatellite::Server::module:73 INFO - loading module 'backupEntity' success
2014/05/19 09:50:12:209 [32354]: ObmSatellite::Server::module:73 INFO - loading module 'cyrusPartition' success
2014/05/19 09:50:12:228 [32354]: ObmSatellite::Server::module:73 INFO - loading module 'postfixAccess' success
2014/05/19 09:50:12:267 [32354]: ObmSatellite::Server::module:73 INFO - loading module 'postfixSmtpInMaps' success
2014/05/19 09:50:12:267 [32354]: ObmSatellite::Server:220 INFO - Trying to bind to SSL port 30000 using certificate /etc/obm/certs/obm_cert.pem (key /etc/obm/certs/obm_cert.pem)
2014/05/19 09:50:12:267 [32354]: ObmSatellite::Server:222 INFO - CA certificate /var/lib/obm-ca/cacert.pem
2014/05/19 09:50:12:342 [32372]: ObmSatellite::Server:175 INFO - child 32372 ready to process requests
2014/05/19 09:50:12:344 [32354]: ObmSatellite::Server:169 INFO - new child forked (PID: 32372), we now have 1 child
2014/05/19 09:50:12:353 [32373]: ObmSatellite::Server:175 INFO - child 32373 ready to process requests
2014/05/19 09:50:12:356 [32354]: ObmSatellite::Server:169 INFO - new child forked (PID: 32373), we now have 2 child
Comment by Stephane COLSON [ 19/May/14 ]

OK on another VM with version 3.0.0~0.alpha0+git20140516-1140-3428720.
I don't know exactly what happened on the other one, except that I have a /var partition fullfilled very shortly.

Generated at Mon Sep 16 00:18:18 CEST 2019 using JIRA 6.1.1#6155-sha1:7188aeec9a6b57d61ea04c52f235f15f55c105e2.