Bonjour,
Merci d’avance pour votre aide.
Cela fait plusieurs fois que j’essaye d’installer BlueMind. Et à chaque fois, j’ai droit à un failed lors de la création du domaine.
Il tente de se connecter à bm-core mais finit par me renvoyer “can’t connect to Node Server at …”
Auriez vous une piste ?
L’installation est faite sur une debian 7
La machine est un dédié chez OVH avec 4 GB de ram et un intel atom N2800
Je vous joins mon log core :
2014-01-16 14:32:28,810 n.b.c.l.Main INFO - Starting Blue Core...
2014-01-16 14:32:28,875 n.b.c.CoreActivator INFO - Activate core.
2014-01-16 14:32:28,879 n.b.c.BMCore INFO - core starting...
2014-01-16 14:32:28,880 n.b.c.BMCore INFO - Loading JDBC pool
2014-01-16 14:32:28,948 n.b.u.RunnableExtensionLoader INFO - net.bluemind.jdbc.oracle.provider.OracleConnectionFactory loaded.
2014-01-16 14:32:28,968 n.b.u.RunnableExtensionLoader INFO - net.bluemind.jdbc.pgsql.provider.PgSQLConnectionFactory loaded.
2014-01-16 14:32:28,969 n.b.u.RunnableExtensionLoader INFO - Loaded 2 implementors of net.bluemind.pool.jdbcdriver
2014-01-16 14:32:28,975 n.b.p.i.BmConfIni WARN - /etc/bm/bm.ini does not exist.
2014-01-16 14:34:48,387 n.b.c.l.Main INFO - Starting Blue Core...
2014-01-16 14:34:48,411 n.b.c.CoreActivator INFO - Activate core.
2014-01-16 14:34:48,413 n.b.c.BMCore INFO - core starting...
2014-01-16 14:34:48,414 n.b.c.BMCore INFO - Loading JDBC pool
2014-01-16 14:34:48,474 n.b.u.RunnableExtensionLoader INFO - net.bluemind.jdbc.oracle.provider.OracleConnectionFactory loaded.
2014-01-16 14:34:48,483 n.b.u.RunnableExtensionLoader INFO - net.bluemind.jdbc.pgsql.provider.PgSQLConnectionFactory loaded.
2014-01-16 14:34:48,483 n.b.u.RunnableExtensionLoader INFO - Loaded 2 implementors of net.bluemind.pool.jdbcdriver
2014-01-16 14:34:48,492 n.b.p.BMPoolActivator INFO - Starting connection pool xxx.xxx.xxx.xxx/bj
2014-01-16 14:34:48,492 n.b.p.BMPoolActivator INFO - dbtype from bm.ini is PGSQL
2014-01-16 14:34:48,730 n.b.p.x.PoolActivator INFO - Pool bundle started.
2014-01-16 14:34:49,134 n.b.p.x.i.DataSource INFO - Starting pool...(jdbc:postgresql://xxx.xxx.xxx.xxx/bj)
2014-01-16 14:34:49,148 n.b.p.x.i.ConnectionPool INFO - Creating Physical connection...
2014-01-16 14:34:49,309 n.b.p.x.i.ConnectionPool INFO - Physical connection established.
2014-01-16 14:34:49,309 n.b.p.x.i.ConnectionPool INFO - Creating Physical connection...
2014-01-16 14:34:49,322 n.b.p.x.i.ConnectionPool INFO - Physical connection established.
2014-01-16 14:34:49,323 n.b.p.x.i.ConnectionPool INFO - Creating Physical connection...
2014-01-16 14:34:49,336 n.b.p.x.i.ConnectionPool INFO - Physical connection established.
2014-01-16 14:34:49,337 n.b.p.x.i.ConnectionPool INFO - Creating Physical connection...
2014-01-16 14:34:49,350 n.b.p.x.i.ConnectionPool INFO - Physical connection established.
2014-01-16 14:34:49,350 n.b.p.x.i.ConnectionPool INFO - Creating Physical connection...
2014-01-16 14:34:49,363 n.b.p.x.i.ConnectionPool INFO - Physical connection established.
2014-01-16 14:34:49,364 n.b.p.x.i.ConnectionPool INFO - Creating Physical connection...
2014-01-16 14:34:49,380 n.b.p.x.i.ConnectionPool INFO - Physical connection established.
2014-01-16 14:34:49,381 n.b.p.x.i.ConnectionPool INFO - Creating Physical connection...
2014-01-16 14:34:49,396 n.b.p.x.i.ConnectionPool INFO - Physical connection established.
2014-01-16 14:34:49,397 n.b.p.x.i.ConnectionPool INFO - Creating Physical connection...
2014-01-16 14:34:49,409 n.b.p.x.i.ConnectionPool INFO - Physical connection established.
2014-01-16 14:34:49,409 n.b.p.x.i.ConnectionPool INFO - Creating Physical connection...
2014-01-16 14:34:49,421 n.b.p.x.i.ConnectionPool INFO - Physical connection established.
2014-01-16 14:34:49,421 n.b.p.x.i.ConnectionPool INFO - Creating Physical connection...
2014-01-16 14:34:49,433 n.b.p.x.i.ConnectionPool INFO - Physical connection established.
2014-01-16 14:34:49,434 n.b.p.x.i.ConnectionPool INFO - Pool State after start is 0 used out of 10 connections
2014-01-16 14:34:49,435 n.b.p.x.i.PingThread INFO - created with 10 JDBC connections.
2014-01-16 14:34:49,441 n.b.c.BMCore INFO - Loading session store
2014-01-16 14:34:49,742 n.b.c.s.m.TimeOutMap INFO - Built session cache with a 1200000ms timeout.
2014-01-16 14:34:49,745 n.b.c.u.RunnableExtensionLoader INFO - MemorySessionStoreFactory loaded.
2014-01-16 14:34:49,745 n.b.c.u.RunnableExtensionLoader INFO - Loaded 1 implementors of net.bluemind.core.sessionstore
2014-01-16 14:34:49,758 n.b.c.s.m.MemorySessionStoreFactory INFO - init
2014-01-16 14:34:49,760 n.b.c.BMCore INFO - session factory will be SHARED (class net.bluemind.core.session.memory.MemorySessionStoreFactory)
2014-01-16 14:34:49,772 n.b.c.u.RunnableExtensionLoader INFO - NginxHookFactory loaded.
2014-01-16 14:34:49,778 n.b.c.u.RunnableExtensionLoader INFO - WebmailHookFactory loaded.
2014-01-16 14:34:49,800 n.b.c.u.RunnableExtensionLoader INFO - HookFactory loaded.
2014-01-16 14:34:49,806 n.b.c.u.RunnableExtensionLoader INFO - DomainAliasHookFactory loaded.
2014-01-16 14:34:49,810 n.b.c.u.RunnableExtensionLoader INFO - NodeKeyHookFactory loaded.
2014-01-16 14:34:49,816 n.b.c.u.RunnableExtensionLoader INFO - PostfixHookFactory loaded.
2014-01-16 14:34:49,821 n.b.c.u.RunnableExtensionLoader INFO - SystemNotificationHookFactory loaded.
2014-01-16 14:34:49,828 n.b.c.u.RunnableExtensionLoader INFO - ArchHookFactory loaded.
2014-01-16 14:34:49,834 n.b.c.u.RunnableExtensionLoader INFO - AclHookFactory loaded.
2014-01-16 14:34:49,834 n.b.c.u.RunnableExtensionLoader INFO - Loaded 9 implementors of net.bluemind.core.systemhookfactory
2014-01-16 14:34:49,844 n.b.c.s.d.PermanentTokenSystemHook INFO - PermanentTokenSystemHook loaded.
2014-01-16 14:34:49,848 n.b.c.h.HookRegistry INFO - *** PRIO: 2147483647 HOOK: net.bluemind.hook.domainalias.EmailTableUpdateHook
2014-01-16 14:34:49,852 n.b.c.h.HookRegistry INFO - *** PRIO: 2147483647 HOOK: net.bluemind.hook.node.NodeKeySystemHook
2014-01-16 14:34:50,155 n.b.c.h.HookRegistry INFO - *** PRIO: 2147483647 HOOK: net.bluemind.hook.acl.AclHook
2014-01-16 14:34:50,195 n.b.c.h.HookRegistry INFO - *** PRIO: 2 HOOK: net.bluemind.system.cyrus.CyrusSystemHook
2014-01-16 14:34:50,198 n.b.c.h.HookRegistry INFO - *** PRIO: 1 HOOK: net.bluemind.system.webmail.WebmailHook
2014-01-16 14:34:50,209 n.b.c.h.HookRegistry INFO - *** PRIO: 1 HOOK: net.bluemind.system.postfix.PostfixHook
2014-01-16 14:34:50,212 n.b.c.h.HookRegistry INFO - *** PRIO: 0 HOOK: net.bluemind.system.nginx.NginxSystemHook
2014-01-16 14:34:50,244 n.b.c.h.HookRegistry INFO - *** PRIO: 0 HOOK: net.bluemind.system.notification.SystemNotification
2014-01-16 14:34:50,246 n.b.c.h.HookRegistry INFO - *** PRIO: 0 HOOK: net.bluemind.archive.core.ArchServerHook
2014-01-16 14:34:50,257 n.b.c.u.RunnableExtensionLoader INFO - CalendarChangeNotification loaded.
2014-01-16 14:34:50,262 n.b.c.u.RunnableExtensionLoader INFO - IcsHookFactory loaded.
2014-01-16 14:34:50,262 n.b.c.u.RunnableExtensionLoader INFO - Loaded 2 implementors of net.bluemind.core.calendarhookfactory
2014-01-16 14:34:50,283 n.b.c.u.RunnableExtensionLoader INFO - ContactChangeNotification loaded.
2014-01-16 14:34:50,292 n.b.c.u.RunnableExtensionLoader INFO - ContactHookFactory loaded.
2014-01-16 14:34:50,292 n.b.c.u.RunnableExtensionLoader INFO - Loaded 2 implementors of net.bluemind.core.contacthookfactory
2014-01-16 14:34:51,072 n.b.c.u.RunnableExtensionLoader INFO - NodeClientFactory loaded.
2014-01-16 14:34:51,073 n.b.c.u.RunnableExtensionLoader INFO - Loaded 1 implementors of net.bluemind.core.nodeclientfactory
2014-01-16 14:34:51,082 n.b.c.u.RunnableExtensionLoader INFO - MailshareSCF loaded.
2014-01-16 14:34:51,084 n.b.c.u.RunnableExtensionLoader INFO - DomainSCF loaded.
2014-01-16 14:34:51,086 n.b.c.u.RunnableExtensionLoader INFO - UserSCF loaded.
2014-01-16 14:34:51,093 n.b.c.u.RunnableExtensionLoader INFO - PostfixSC loaded.
2014-01-16 14:34:51,093 n.b.c.u.RunnableExtensionLoader INFO - Loaded 4 implementors of net.bluemind.core.sanitycheckerfactory
2014-01-16 14:34:51,094 n.b.c.s.SanityChecksRegistry INFO - Registered 4 sanity check factories through plugins
2014-01-16 14:34:51,097 n.b.c.u.RunnableExtensionLoader INFO - Loaded 0 implementors of net.bluemind.core.passwordpolicyfactory
2014-01-16 14:34:51,118 n.b.c.u.RunnableExtensionLoader INFO - ImapCustomRequirements loaded.
2014-01-16 14:34:51,123 n.b.c.u.RunnableExtensionLoader INFO - PostfixCustomRequirements loaded.
2014-01-16 14:34:51,123 n.b.c.u.RunnableExtensionLoader INFO - Loaded 2 implementors of net.bluemind.core.custompropertyrequirements
2014-01-16 14:34:51,124 n.b.c.c.p.CustomPropertyHome INFO - Providing requirements for plugin cyrus
2014-01-16 14:34:51,202 n.b.c.c.p.CustomPropertyHome INFO - [cyrus]: property domain::max_quota provided.
2014-01-16 14:34:51,205 n.b.c.c.p.CustomPropertyHome INFO - [cyrus]: property domain::mailshare_def_quota provided.
2014-01-16 14:34:51,208 n.b.c.c.p.CustomPropertyHome INFO - [cyrus]: property domain::def_quota provided.
2014-01-16 14:34:51,211 n.b.c.c.p.CustomPropertyHome INFO - [cyrus]: property domain::mailshare_max_quota provided.
2014-01-16 14:34:51,235 n.b.c.c.p.CustomPropertyHome INFO - Providing requirements for plugin postfix
2014-01-16 14:34:51,243 n.b.c.c.p.CustomPropertyHome INFO - [postfix]: property domain::slave_relay provided.
2014-01-16 14:34:51,246 n.b.c.c.p.CustomPropertyHome INFO - [postfix]: property domain::split_relay provided.
2014-01-16 14:34:51,346 n.b.c.u.RunnableExtensionLoader INFO - CalendarSettingsRequirements loaded.
2014-01-16 14:34:51,352 n.b.c.u.RunnableExtensionLoader INFO - ContactSettingsRequirements loaded.
2014-01-16 14:34:51,359 n.b.c.u.RunnableExtensionLoader INFO - GeneralSettingsRequirements loaded.
2014-01-16 14:34:51,366 n.b.c.u.RunnableExtensionLoader INFO - MailSettingsRequirements loaded.
2014-01-16 14:34:51,367 n.b.c.u.RunnableExtensionLoader INFO - Loaded 4 implementors of net.bluemind.core.customsettingrequirements
2014-01-16 14:34:51,367 n.b.c.c.s.CustomSettingHome INFO - reset stored settings
2014-01-16 14:34:51,688 n.b.c.c.s.CustomSettingHome INFO - Providing settings from net.bluemind.custom.settings.calendar
2014-01-16 14:34:51,702 n.b.c.c.s.CustomSettingHome INFO - [work_hours_end]: setting provided.
2014-01-16 14:34:51,704 n.b.c.c.s.CustomSettingHome INFO - [acl]: setting provided.
2014-01-16 14:34:51,706 n.b.c.c.s.CustomSettingHome INFO - [set_defaultview]: setting provided.
2014-01-16 14:34:51,708 n.b.c.c.s.CustomSettingHome INFO - [set_showweekends]: setting provided.
2014-01-16 14:34:51,711 n.b.c.c.s.CustomSettingHome INFO - [work_hours_start]: setting provided.
2014-01-16 14:34:51,713 n.b.c.c.s.CustomSettingHome INFO - [show_declined_events]: setting provided.
2014-01-16 14:34:51,714 n.b.c.c.s.CustomSettingHome INFO - [management]: setting provided.
2014-01-16 14:34:51,715 n.b.c.c.s.CustomSettingHome INFO - [set_day_weekstart]: setting provided.
2014-01-16 14:34:51,717 n.b.c.c.s.CustomSettingHome INFO - [subscription]: setting provided.
2014-01-16 14:34:51,718 n.b.c.c.s.CustomSettingHome INFO - [working_days]: setting provided.
2014-01-16 14:34:51,735 n.b.c.c.s.CustomSettingHome INFO - [acl]: add value admin
2014-01-16 14:34:51,736 n.b.c.c.s.CustomSettingHome INFO - [acl]: add value write
2014-01-16 14:34:51,736 n.b.c.c.s.CustomSettingHome INFO - [acl]: add value read
2014-01-16 14:34:51,737 n.b.c.c.s.CustomSettingHome INFO - [acl]: add value access
2014-01-16 14:34:51,737 n.b.c.c.s.CustomSettingHome INFO - [set_defaultview]: add value agenda
2014-01-16 14:34:51,738 n.b.c.c.s.CustomSettingHome INFO - [set_defaultview]: add value month
2014-01-16 14:34:51,738 n.b.c.c.s.CustomSettingHome INFO - [set_defaultview]: add value day
2014-01-16 14:34:51,739 n.b.c.c.s.CustomSettingHome INFO - [set_defaultview]: add value week
2014-01-16 14:34:51,739 n.b.c.c.s.CustomSettingHome INFO - [set_showweekends]: add value yes
2014-01-16 14:34:51,740 n.b.c.c.s.CustomSettingHome INFO - [set_showweekends]: add value no
2014-01-16 14:34:51,740 n.b.c.c.s.CustomSettingHome INFO - [show_declined_events]: add value yes
2014-01-16 14:34:51,741 n.b.c.c.s.CustomSettingHome INFO - [show_declined_events]: add value no
2014-01-16 14:34:51,741 n.b.c.c.s.CustomSettingHome INFO - [set_day_weekstart]: add value monday
2014-01-16 14:34:51,741 n.b.c.c.s.CustomSettingHome INFO - [set_day_weekstart]: add value sunday
2014-01-16 14:34:51,742 n.b.c.c.s.CustomSettingHome INFO - [working_days]: add value 6::sun
2014-01-16 14:34:51,742 n.b.c.c.s.CustomSettingHome INFO - [working_days]: add value 3::thu
2014-01-16 14:34:51,743 n.b.c.c.s.CustomSettingHome INFO - [working_days]: add value 5::sat
2014-01-16 14:34:51,743 n.b.c.c.s.CustomSettingHome INFO - [working_days]: add value 1::tue
2014-01-16 14:34:51,744 n.b.c.c.s.CustomSettingHome INFO - [working_days]: add value 2::wed
2014-01-16 14:34:51,744 n.b.c.c.s.CustomSettingHome INFO - [working_days]: add value 4::fri
2014-01-16 14:34:51,745 n.b.c.c.s.CustomSettingHome INFO - [working_days]: add value 0::mon
2014-01-16 14:34:51,852 n.b.c.c.s.CustomSettingHome INFO - Providing settings from net.bluemind.custom.settings.contact
2014-01-16 14:34:51,859 n.b.c.c.s.CustomSettingHome INFO - [foldermanagement]: setting provided.
2014-01-16 14:34:51,861 n.b.c.c.s.CustomSettingHome INFO - [acl]: setting provided.
2014-01-16 14:34:51,863 n.b.c.c.s.CustomSettingHome INFO - [foldersubscription]: setting provided.
2014-01-16 14:34:51,876 n.b.c.c.s.CustomSettingHome INFO - [acl]: add value admin
2014-01-16 14:34:51,877 n.b.c.c.s.CustomSettingHome INFO - [acl]: add value write
2014-01-16 14:34:51,878 n.b.c.c.s.CustomSettingHome INFO - [acl]: add value read
2014-01-16 14:34:51,893 n.b.c.c.s.CustomSettingHome INFO - Providing settings from net.bluemind.custom.settings.general
2014-01-16 14:34:51,900 n.b.c.c.s.CustomSettingHome INFO - [set_timeformat]: setting provided.
2014-01-16 14:34:51,902 n.b.c.c.s.CustomSettingHome INFO - [set_lang]: setting provided.
2014-01-16 14:34:51,904 n.b.c.c.s.CustomSettingHome INFO - [set_timezone]: setting provided.
2014-01-16 14:34:51,906 n.b.c.c.s.CustomSettingHome INFO - [set_date]: setting provided.
2014-01-16 14:34:51,908 n.b.c.c.s.CustomSettingHome INFO - [password]: setting provided.
2014-01-16 14:34:51,910 n.b.c.c.s.CustomSettingHome INFO - [default_app]: setting provided.
2014-01-16 14:34:51,926 n.b.c.c.s.CustomSettingHome INFO - [set_timeformat]: add value h:mma
2014-01-16 14:34:51,927 n.b.c.c.s.CustomSettingHome INFO - [set_timeformat]: add value HH:mm
2014-01-16 14:34:51,928 n.b.c.c.s.CustomSettingHome INFO - [set_lang]: add value sk
2014-01-16 14:34:51,928 n.b.c.c.s.CustomSettingHome INFO - [set_lang]: add value pl
2014-01-16 14:34:51,928 n.b.c.c.s.CustomSettingHome INFO - [set_lang]: add value fr
2014-01-16 14:34:51,929 n.b.c.c.s.CustomSettingHome INFO - [set_lang]: add value en
2014-01-16 14:34:51,929 n.b.c.c.s.CustomSettingHome INFO - [set_lang]: add value es
2014-01-16 14:34:51,929 n.b.c.c.s.CustomSettingHome INFO - [set_date]: add value MM/dd/yyyy
2014-01-16 14:34:51,930 n.b.c.c.s.CustomSettingHome INFO - [set_date]: add value dd/MM/yyyy
2014-01-16 14:34:51,930 n.b.c.c.s.CustomSettingHome INFO - [set_date]: add value yyyy-MM-dd
2014-01-16 14:34:51,931 n.b.c.c.s.CustomSettingHome INFO - [default_app]: add value /webmail/
2014-01-16 14:34:51,931 n.b.c.c.s.CustomSettingHome INFO - [default_app]: add value /cal/
2014-01-16 14:34:51,951 n.b.c.c.s.CustomSettingHome INFO - Providing settings from net.bluemind.custom.settings.mail
2014-01-16 14:34:51,958 n.b.c.c.s.CustomSettingHome INFO - [acl]: setting provided.
2014-01-16 14:34:51,960 n.b.c.c.s.CustomSettingHome INFO - [vacation]: setting provided.
2014-01-16 14:34:51,962 n.b.c.c.s.CustomSettingHome INFO - [identities]: setting provided.
2014-01-16 14:34:51,965 n.b.c.c.s.CustomSettingHome INFO - [rc]: setting provided.
2014-01-16 14:34:51,967 n.b.c.c.s.CustomSettingHome INFO - [sieve]: setting provided.
2014-01-16 14:34:51,968 n.b.c.c.s.CustomSettingHome INFO - [forward]: setting provided.
2014-01-16 14:34:51,982 n.b.c.c.s.CustomSettingHome INFO - [acl]: add value admin
2014-01-16 14:34:51,982 n.b.c.c.s.CustomSettingHome INFO - [acl]: add value write
2014-01-16 14:34:51,983 n.b.c.c.s.CustomSettingHome INFO - [acl]: add value read
2014-01-16 14:34:51,983 n.b.c.c.s.CustomSettingHome INFO - [rc]: add value /webmail/?_task=settings&_action=
2014-01-16 14:34:52,026 n.b.u.RunnableExtensionLoader INFO - net.bluemind.system.cyrus.ProtectMailImap loaded.
2014-01-16 14:34:52,066 n.b.u.RunnableExtensionLoader INFO - net.bluemind.system.cyrus.ProtectMailImapFrontend loaded.
2014-01-16 14:34:52,072 n.b.u.RunnableExtensionLoader INFO - net.bluemind.core.doc.crappystore.ProtectCrappyStore loaded.
2014-01-16 14:34:52,077 n.b.u.RunnableExtensionLoader INFO - net.bluemind.archive.store.snappy.ProtectSnappyStore loaded.
2014-01-16 14:34:52,084 n.b.u.RunnableExtensionLoader INFO - net.bluemind.dataprotect.pgsql.ProtectPostgresql loaded.
2014-01-16 14:34:52,086 n.b.u.RunnableExtensionLoader INFO - net.bluemind.system.postfix.NoSmtpProtect loaded.
2014-01-16 14:34:52,088 n.b.u.RunnableExtensionLoader INFO - net.bluemind.core.dataprotect.NoBackupTags loaded.
2014-01-16 14:34:52,089 n.b.u.RunnableExtensionLoader INFO - Loaded 7 implementors of net.bluemind.dataprotect.worker
2014-01-16 14:34:52,089 n.b.u.RunnableExtensionLoader INFO - Loaded 0 implementors of net.bluemind.dataprotect.upgrade
2014-01-16 14:34:52,090 n.b.d.i.Workers WARN - Your Blue Mind lacks DataProtect upgrade support
2014-01-16 14:34:52,091 n.b.u.RunnableExtensionLoader INFO - net.bluemind.dataprotect.core.DataProtectJob loaded.
2014-01-16 14:34:52,096 n.b.u.RunnableExtensionLoader INFO - net.bluemind.archive.core.ArchiveJob loaded.
2014-01-16 14:34:52,105 n.b.u.RunnableExtensionLoader INFO - net.bluemind.core.jobs.event.EventAlertJob loaded.
2014-01-16 14:34:52,109 n.b.u.RunnableExtensionLoader INFO - net.bluemind.core.jobs.oof.VacationJob loaded.
2014-01-16 14:34:52,113 n.b.u.RunnableExtensionLoader INFO - net.bluemind.core.jobs.quota.QuotaGatheringJob loaded.
2014-01-16 14:34:52,116 n.b.u.RunnableExtensionLoader INFO - net.bluemind.core.jobs.cleanup.JobExecutionsCleanJob loaded.
2014-01-16 14:34:52,116 n.b.u.RunnableExtensionLoader INFO - Loaded 6 implementors of net.bluemind.core.bluejob
2014-01-16 14:34:52,142 n.b.c.j.JobRegistry INFO - ******* First scheduler tick at Thu Jan 16 14:36:00 GMT 2014
2014-01-16 14:34:52,189 n.b.c.u.RunnableExtensionLoader INFO - Loaded 0 implementors of net.bluemind.core.authenticationservice
2014-01-16 14:34:52,192 n.b.c.s.a.i.AuthenticationRegistry INFO - Add default authentication service: class net.bluemind.core.server.auth.impl.DatabaseAuthenticationService
2014-01-16 14:34:52,210 n.b.c.u.RunnableExtensionLoader INFO - Loaded 0 implementors of net.bluemind.core.loginvalidation
2014-01-16 14:34:52,217 n.b.c.u.CoreProvider INFO - registered ICalendar calendar implementation: net.bluemind.core.calendar.CalendarBindingImpl$1@5cf06070
2014-01-16 14:34:52,429 n.b.c.u.CoreProvider INFO - registered ICalendar todo implementation: net.bluemind.core.calendar.CalendarBindingImpl$2@65a05309
2014-01-16 14:34:52,455 n.b.c.u.CoreProvider INFO - registered IAddressBook: net.bluemind.core.contact.AddressBookBindingImpl$1@229f1a77
2014-01-16 14:34:52,547 n.b.c.u.CoreProvider INFO - registered ISetting implementation: net.bluemind.core.setting.SettingBindingImpl$1@7dee8a4b
2014-01-16 14:34:52,597 n.b.c.u.CoreProvider INFO - registered IGroup implementation: net.bluemind.core.group.GroupBindingImpl$1@7038a5b7
2014-01-16 14:34:52,649 n.b.c.u.CoreProvider INFO - registered IAcl implementation: net.bluemind.core.acl.AclBindingImpl$1@1015cde3
2014-01-16 14:34:52,696 n.b.c.u.CoreProvider INFO - registered IUser implementation: net.bluemind.core.user.UserBindingImpl$1@45e74a9b
2014-01-16 14:34:52,737 n.b.c.u.CoreProvider INFO - registered IResource: net.bluemind.core.resource.ResourceBindingImpl$1@21e0051e
2014-01-16 14:34:52,771 n.b.c.u.CoreProvider INFO - registered IMailshare implementation: net.bluemind.core.mailshare.MailshareBindingImpl$1@311a3c9d
2014-01-16 14:34:52,813 n.b.c.u.CoreProvider INFO - registered IDirectoryimplementation: net.bluemind.core.directory.DirectoryBindingImpl$1@5e3b891
2014-01-16 14:34:53,706 n.b.c.r.RBACBindingImpl INFO - Permission role systemManagement created
2014-01-16 14:34:55,827 n.b.c.r.RBACBindingImpl INFO - Permission role userManagement created
2014-01-16 14:34:56,255 n.b.h.c.NonOsgiActivator WARN - failed to locate bm/mq server. falling back to localhost
2014-01-16 14:34:57,034 n.b.c.s.m.MemorySessionStoreFactory INFO - MQ connection completed. Session replication activated
2014-01-16 14:34:57,227 n.b.c.UserManagement INFO - MQ connection for push queues completed.
2014-01-16 14:35:00,285 n.b.c.r.RBACBindingImpl INFO - Permission role groupwareUsage created
2014-01-16 14:35:00,286 n.b.c.u.CoreProvider INFO - registered IRoleBasedAccessControl: net.bluemind.core.rbac.RBACBindingImpl$2@253844dd
2014-01-16 14:35:00,302 n.b.c.u.CoreProvider INFO - registered ISolr: net.bluemind.core.solr.SolrBindingImpl@5d6f480e
2014-01-16 14:35:00,322 n.b.c.u.CoreProvider INFO - registered IDistributionList: net.bluemind.core.distributionlist.DistributionListBindingImpl$1@43040ef8
2014-01-16 14:35:00,342 n.b.c.u.CoreProvider INFO - registered IInfo: net.bluemind.core.info.InfoBindingImpl$1@1b2fde11
2014-01-16 14:35:00,357 n.b.c.u.CoreProvider INFO - registered ISieveFilter: net.bluemind.core.sievefilter.SieveFilterBindingImpl$1@35297cd1
2014-01-16 14:35:00,376 n.b.c.u.CoreProvider INFO - registered IJob: net.bluemind.core.jobs.impl.JobBindingImpl$1@66f27abe
2014-01-16 14:35:00,410 n.b.c.u.CoreProvider INFO - registered IIdentity: net.bluemind.core.mailidentity.IdentityBindingImpl$1@2c4b6707
2014-01-16 14:35:00,421 n.b.c.u.CoreProvider INFO - registered ITasks: net.bluemind.core.taskref.TaskBindingImpl@5b6ccee5
2014-01-16 14:35:00,440 n.b.c.u.CoreProvider INFO - registered IDocumentFolder: net.bluemind.core.handler.documentfolder.DocumentFolderBindingImpl$1@33204537
2014-01-16 14:35:00,453 n.b.c.h.d.DocumentFolderHandler INFO - DocumentFolderHandler created
2014-01-16 14:35:00,453 n.b.c.u.RunnableExtensionLoader INFO - DocumentFolderHandler loaded.
2014-01-16 14:35:00,460 n.b.c.u.RunnableExtensionLoader INFO - BackupTagsProvider loaded.
2014-01-16 14:35:00,462 n.b.c.u.RunnableExtensionLoader INFO - ArchiveTagProvider loaded.
2014-01-16 14:35:00,463 n.b.c.u.RunnableExtensionLoader INFO - Loaded 2 implementors of net.bluemind.core.handler.host.templatecustomizer
2014-01-16 14:35:00,465 n.b.c.h.h.SystemHandler INFO - system handler created.
2014-01-16 14:35:00,753 n.b.c.h.h.SystemBindingImpl INFO - system tag mail/smtp_in created.
2014-01-16 14:35:00,770 n.b.c.h.h.SystemBindingImpl INFO - system tag mail/smtp_out created.
2014-01-16 14:35:00,828 n.b.c.h.h.SystemBindingImpl INFO - system tag mail/imap created.
2014-01-16 14:35:00,841 n.b.c.h.h.SystemBindingImpl INFO - system tag mail/imap_frontend created.
2014-01-16 14:35:00,853 n.b.c.h.h.SystemBindingImpl INFO - system tag mail/archive created.
2014-01-16 14:35:00,866 n.b.c.h.h.SystemBindingImpl INFO - system tag solr/event created.
2014-01-16 14:35:00,883 n.b.c.h.h.SystemBindingImpl INFO - system tag solr/contact created.
2014-01-16 14:35:00,894 n.b.c.h.h.SystemBindingImpl INFO - system tag solr/webmail created.
2014-01-16 14:35:00,908 n.b.c.h.h.SystemBindingImpl INFO - system tag bm/core created.
2014-01-16 14:35:00,920 n.b.c.h.h.SystemBindingImpl INFO - system tag bm/mq created.
2014-01-16 14:35:01,011 n.b.c.h.h.SystemBindingImpl INFO - system tag bm/ac created.
2014-01-16 14:35:01,025 n.b.c.h.h.SystemBindingImpl INFO - system tag bm/cal created.
2014-01-16 14:35:01,036 n.b.c.h.h.SystemBindingImpl INFO - system tag bm/webmail created.
2014-01-16 14:35:01,050 n.b.c.h.h.SystemBindingImpl INFO - system tag bm/contact created.
2014-01-16 14:35:01,061 n.b.c.h.h.SystemBindingImpl INFO - system tag bm/settings created.
2014-01-16 14:35:01,075 n.b.c.h.h.SystemBindingImpl INFO - system tag bm/redirector created.
2014-01-16 14:35:01,086 n.b.c.h.h.SystemBindingImpl INFO - system tag bm/nginx created.
2014-01-16 14:35:01,120 n.b.c.h.h.SystemBindingImpl INFO - system tag bm/pgsql created.
2014-01-16 14:35:01,186 n.b.c.h.h.SystemBindingImpl INFO - system tag backup/server created.
2014-01-16 14:35:01,187 n.b.c.u.CoreProvider INFO - registered ISystem implementation: net.bluemind.core.handler.host.SystemBindingImpl$1@54790d5a
2014-01-16 14:35:01,226 n.b.c.u.RunnableExtensionLoader INFO - SystemHandler loaded.
2014-01-16 14:35:01,236 n.b.c.u.CoreProvider INFO - registered IDataProtect: net.bluemind.dataprotect.core.impl.DataProtectBinding@231d4b69
2014-01-16 14:35:01,252 n.b.c.u.RunnableExtensionLoader INFO - DPHandler loaded.
2014-01-16 14:35:01,264 n.b.c.u.RunnableExtensionLoader INFO - CyrusDomainUi loaded.
2014-01-16 14:35:01,266 n.b.c.u.RunnableExtensionLoader INFO - PostfixUiExtension loaded.
2014-01-16 14:35:01,267 n.b.c.u.RunnableExtensionLoader INFO - Loaded 2 implementors of net.bluemind.core.handler.ui.extensionprovider
2014-01-16 14:35:01,274 n.b.c.u.RunnableExtensionLoader INFO - CalendarSettingsExtensionProvider loaded.
2014-01-16 14:35:01,277 n.b.c.u.RunnableExtensionLoader INFO - ContactSettingsExtensionProvider loaded.
2014-01-16 14:35:01,285 n.b.c.u.RunnableExtensionLoader INFO - DLCOutlookSettingsExtensionProvider loaded.
2014-01-16 14:35:01,293 n.b.c.u.RunnableExtensionLoader INFO - DLCTbirdSettingsExtensionProvider loaded.
2014-01-16 14:35:01,296 n.b.c.u.RunnableExtensionLoader INFO - GeneralSettingsExtensionProvider loaded.
2014-01-16 14:35:01,301 n.b.c.u.RunnableExtensionLoader INFO - DLCenterSettingsExtensionProvider loaded.
2014-01-16 14:35:01,304 n.b.c.u.RunnableExtensionLoader INFO - MailSettingsExtensionProvider loaded.
2014-01-16 14:35:01,305 n.b.c.u.RunnableExtensionLoader INFO - Loaded 7 implementors of net.bluemind.core.handler.ui.settingprovider
2014-01-16 14:35:01,319 n.b.c.u.RunnableExtensionLoader INFO - UiHandler loaded.
2014-01-16 14:35:01,327 n.b.c.u.RunnableExtensionLoader INFO - CrappyStoreFactory loaded.
2014-01-16 14:35:01,327 n.b.c.u.RunnableExtensionLoader INFO - Loaded 1 implementors of net.bluemind.core.handler.doc.documentstore
2014-01-16 14:35:01,336 n.b.c.u.CoreProvider INFO - registered IDocument: net.bluemind.core.handler.doc.DocBindingImpl$1@7ebf324
2014-01-16 14:35:01,348 n.b.c.h.d.DocHandler INFO - docHandler created
2014-01-16 14:35:01,348 n.b.c.u.RunnableExtensionLoader INFO - DocHandler loaded.
2014-01-16 14:35:01,358 n.b.c.u.CoreProvider INFO - registered IMail: net.bluemind.handler.mail.MailBindingImpl@39f400a3
2014-01-16 14:35:01,362 n.b.c.u.RunnableExtensionLoader INFO - MailHandler loaded.
2014-01-16 14:35:01,378 n.b.c.u.CoreProvider INFO - registered IHierarchicalStorage implementation: net.bluemind.archive.core.HierarchicalStorageImpl$1@17d8ac99
2014-01-16 14:35:01,387 n.b.c.u.RunnableExtensionLoader INFO - HSMHandler loaded.
2014-01-16 14:35:01,397 n.b.c.u.CoreProvider INFO - registered IPrint: net.bluemind.core.handler.print.PrintBindingImpl$1@63a59b5a
2014-01-16 14:35:01,402 n.b.c.u.RunnableExtensionLoader INFO - PrintHandler loaded.
2014-01-16 14:35:01,402 n.b.c.u.RunnableExtensionLoader INFO - Loaded 8 implementors of net.bluemind.core.handler
2014-01-16 14:35:01,413 n.b.c.BMCore INFO - Started Blue Mind Core 2.0.11195 (2.0.14)
2014-01-16 14:35:01,413 n.b.c.l.Main INFO - starting jetty...
2014-01-16 14:35:01,444 o.m.log INFO - Logging to Logger[org.mortbay.log] via org.mortbay.log.Slf4jLog
2014-01-16 14:35:01,694 o.m.log INFO - jetty-6.1.x
2014-01-16 14:35:01,808 o.m.log INFO - Started SelectChannelConnector@0.0.0.0:8085
2014-01-16 14:35:01,813 n.b.c.l.Main INFO - jetty started, loading servlets...
2014-01-16 14:35:01,864 n.b.c.s.SyncServlet INFO - init of SyncServlet
2014-01-16 14:35:01,944 n.b.l.s.ServoRegistry INFO - Building for net.bluemind.core.server.SyncServlet@7ae143db, id: SyncServlet
2014-01-16 14:35:02,019 n.b.l.s.ServoRegistry INFO - Built NumberFieldMonitor{config=MonitorConfig{name=totalQueries, tags=type=SyncServlet, policy=DefaultPublishingPolicy}, field=private java.util.concurrent.atomic.AtomicLong net.bluemind.core.server.SyncServlet.totalQueries}
2014-01-16 14:35:02,020 n.b.l.s.ServoRegistry INFO - Building for net.bluemind.core.server.SyncServlet@7ae143db, id: SyncServlet
2014-01-16 14:35:02,021 n.b.l.s.ServoRegistry INFO - Built NumberFieldMonitor{config=MonitorConfig{name=totalTimeMs, tags=type=SyncServlet, policy=DefaultPublishingPolicy}, field=private java.util.concurrent.atomic.AtomicLong net.bluemind.core.server.SyncServlet.totalTime}
2014-01-16 14:35:02,022 n.b.l.s.ServoRegistry INFO - Building for net.bluemind.core.server.SyncServlet@7ae143db, id: SyncServlet
2014-01-16 14:35:02,023 n.b.l.s.ServoRegistry INFO - Built NumberFieldMonitor{config=MonitorConfig{name=responseTime, tags=type=SyncServlet, policy=DefaultPublishingPolicy}, field=private java.util.concurrent.atomic.AtomicInteger net.bluemind.core.server.SyncServlet.responseTime}
2014-01-16 14:35:02,046 n.b.c.l.Main INFO - Accepting queries on port 8085
2014-01-16 14:35:20,258 n.b.s.n.SystemNotification INFO - Producer on jms.topic.bm.system.notification created.
2014-01-16 14:35:20,279 n.b.c.n.CalendarChangeNotification INFO - Producer on jms.topic.bm.calendar.notification created.
2014-01-16 14:35:20,303 n.b.c.n.ContactChangeNotification INFO - Producer on jms.topic.bm.contact.notification created.
2014-01-16 14:36:00,132 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.cleanup.JobExecutionsCleanJob
2014-01-16 14:36:00,145 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:36:00,188 n.b.c.j.i.JobBindingImpl INFO - getJobFromId Vacation
2014-01-16 14:36:00,197 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:36:00,232 n.b.c.j.i.JobBindingImpl INFO - getJobFromId EventAlert
2014-01-16 14:36:00,236 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:36:00,261 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.archive.core.ArchiveJob
2014-01-16 14:36:00,268 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:36:00,273 n.b.c.j.i.Scheduler INFO - Finishing Vacation-global.virt-1389882960198
2014-01-16 14:36:00,274 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389882960198] progress is now 100%.
2014-01-16 14:36:00,278 n.b.c.j.i.ExecutionRecorder INFO - recording execution of Vacation-global.virt-1389882960198
2014-01-16 14:36:00,294 n.b.c.j.i.JobHome INFO - recording execution for Vacation-global.virt-1389882960198...
2014-01-16 14:36:00,298 n.b.c.j.i.Scheduler INFO - Finishing EventAlert-global.virt-1389882960238
2014-01-16 14:36:00,298 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389882960238] progress is now 100%.
2014-01-16 14:36:00,304 n.b.c.j.i.ExecutionRecorder INFO - recording execution of EventAlert-global.virt-1389882960238
2014-01-16 14:36:00,308 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.dataprotect.core.DataProtectJob
2014-01-16 14:36:00,311 n.b.c.j.i.JobHome INFO - run plan for Vacation@global.virt updated with last_run set to id 1 (start: Thu Jan 16 14:36:00 GMT 2014)
2014-01-16 14:36:00,312 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:36:00,316 n.b.c.j.i.JobHome INFO - recording execution for EventAlert-global.virt-1389882960238...
2014-01-16 14:36:00,317 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:36:00,325 n.b.d.c.DataProtectJob INFO - not planned, last run was 68secs ago.
2014-01-16 14:36:00,326 n.b.d.c.DataProtectJob INFO - less than one day ago, skipping execution.
2014-01-16 14:36:00,328 n.b.c.j.i.JobHome INFO - run plan for EventAlert@global.virt updated with last_run set to id 2 (start: Thu Jan 16 14:36:00 GMT 2014)
2014-01-16 14:36:00,329 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:36:00,334 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389882960198] finished and recorded: SUCCESS, duration: 75ms.
2014-01-16 14:36:00,339 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.quota.QuotaGatheringJob
2014-01-16 14:36:00,344 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:36:00,347 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389882960238] finished and recorded: SUCCESS, duration: 60ms.
2014-01-16 14:37:00,034 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.cleanup.JobExecutionsCleanJob
2014-01-16 14:37:00,042 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:37:00,084 n.b.c.j.i.JobBindingImpl INFO - getJobFromId Vacation
2014-01-16 14:37:00,094 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:37:00,123 n.b.c.j.i.JobBindingImpl INFO - getJobFromId EventAlert
2014-01-16 14:37:00,128 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:37:00,146 n.b.c.j.i.Scheduler INFO - Finishing EventAlert-global.virt-1389883020129
2014-01-16 14:37:00,147 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883020129] progress is now 100%.
2014-01-16 14:37:00,148 n.b.c.j.i.ExecutionRecorder INFO - recording execution of EventAlert-global.virt-1389883020129
2014-01-16 14:37:00,154 n.b.c.j.i.JobHome INFO - recording execution for EventAlert-global.virt-1389883020129...
2014-01-16 14:37:00,156 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.archive.core.ArchiveJob
2014-01-16 14:37:00,165 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:37:00,166 n.b.c.j.i.JobHome INFO - run plan for EventAlert@global.virt updated with last_run set to id 3 (start: Thu Jan 16 14:37:00 GMT 2014)
2014-01-16 14:37:00,167 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:37:00,189 n.b.c.j.i.Scheduler INFO - Finishing Vacation-global.virt-1389883020096
2014-01-16 14:37:00,190 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.dataprotect.core.DataProtectJob
2014-01-16 14:37:00,190 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883020096] progress is now 100%.
2014-01-16 14:37:00,191 n.b.c.j.i.ExecutionRecorder INFO - recording execution of Vacation-global.virt-1389883020096
2014-01-16 14:37:00,195 n.b.c.j.i.JobHome INFO - recording execution for Vacation-global.virt-1389883020096...
2014-01-16 14:37:00,197 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883020129] finished and recorded: SUCCESS, duration: 18ms.
2014-01-16 14:37:00,200 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:37:00,202 n.b.d.c.DataProtectJob INFO - not planned, last run was 128secs ago.
2014-01-16 14:37:00,202 n.b.d.c.DataProtectJob INFO - less than one day ago, skipping execution.
2014-01-16 14:37:00,206 n.b.c.j.i.JobHome INFO - run plan for Vacation@global.virt updated with last_run set to id 4 (start: Thu Jan 16 14:37:00 GMT 2014)
2014-01-16 14:37:00,206 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:37:00,221 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.quota.QuotaGatheringJob
2014-01-16 14:37:00,223 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883020096] finished and recorded: SUCCESS, duration: 94ms.
2014-01-16 14:37:00,226 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:37:01,925 n.b.c.s.a.i.AuthenticationRegistry INFO - Validate password using service net.bluemind.core.server.auth.impl.DatabaseAuthenticationService: YES in 10ms.
2014-01-16 14:37:02,101 n.b.c.UserManagement INFO - admin0@global.virt logged in from setup-wizard. auth type: BM DB (mail: null) on bm-core 2.0.11195
2014-01-16 14:37:02,208 n.b.c.UserManagement INFO - User access loaded for admin0
2014-01-16 14:37:02,307 n.b.c.s.SyncServlet INFO - handler responded to login/doLogin in 484ms.
2014-01-16 14:37:02,609 n.b.c.h.h.SystemSanityCheckHome WARN - FIXME BJR(40) if name is set, must validate a regepx
2014-01-16 14:37:12,784 n.b.c.s.SyncServlet ERROR - sending ServerFault: Can't connect to Node Server at xxx.xxx.xxx.xxx
net.bluemind.core.api.fault.ServerFault: Can't connect to Node Server at xxx.xxx.xxx.xxx
at net.bluemind.node.client.pool.SessionPool.newSession(SessionPool.java:92) ~[na:na]
at net.bluemind.node.client.pool.SessionPool.get(SessionPool.java:71) ~[na:na]
at net.bluemind.node.client.SChNodeClient.newSession(SChNodeClient.java:68) ~[na:na]
at net.bluemind.node.client.SChNodeClient.ping(SChNodeClient.java:144) ~[na:na]
at net.bluemind.core.handler.host.SystemBindingImpl.postCreateHost(SystemBindingImpl.java:331) ~[na:na]
at net.bluemind.core.handler.host.SystemBindingImpl.create(SystemBindingImpl.java:314) ~[na:na]
at net.bluemind.core.handler.host.SystemHandler$13.execute(SystemHandler.java:269) ~[na:na]
at net.bluemind.core.server.SecureMethod.execute(SecureMethod.java:56) ~[na:na]
at net.bluemind.core.server.AbstractSyncHandler.handle(AbstractSyncHandler.java:94) ~[na:na]
at net.bluemind.core.server.SyncServlet.handleQuery(SyncServlet.java:141) ~[na:na]
at net.bluemind.core.server.SyncServlet.service(SyncServlet.java:99) ~[na:na]
at javax.servlet.http.HttpServlet.service(HttpServlet.java:820) ~[na:na]
at org.eclipse.equinox.http.registry.internal.ServletManager$ServletWrapper.service(ServletManager.java:180) ~[na:na]
at org.eclipse.equinox.http.servlet.internal.ServletRegistration.service(ServletRegistration.java:61) ~[na:na]
at org.eclipse.equinox.http.servlet.internal.ProxyServlet.processAlias(ProxyServlet.java:126) ~[na:na]
at org.eclipse.equinox.http.servlet.internal.ProxyServlet.service(ProxyServlet.java:68) ~[na:na]
at javax.servlet.http.HttpServlet.service(HttpServlet.java:820) ~[na:na]
at org.eclipse.equinox.http.jetty.internal.HttpServerManager$InternalHttpServiceServlet.service(HttpServerManager.java:317) ~[na:na]
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:511) ~[na:na]
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:390) ~[na:na]
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:182) ~[na:na]
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:765) ~[na:na]
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152) ~[na:na]
at org.mortbay.jetty.Server.handle(Server.java:326) ~[na:na]
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:542) ~[na:na]
at org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:939) ~[na:na]
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:756) ~[na:na]
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:218) ~[na:na]
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:404) ~[na:na]
at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:409) ~[na:na]
at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:582) ~[na:na]
Caused by: com.jcraft.jsch.JSchException: Session.connect: java.net.SocketTimeoutException: Read timed out
at com.jcraft.jsch.Session.connect(Session.java:527) ~[na:na]
at net.bluemind.node.client.pool.SessionPool.newSession(SessionPool.java:88) ~[na:na]
... 30 common frames omitted
2014-01-16 14:38:00,035 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.cleanup.JobExecutionsCleanJob
2014-01-16 14:38:00,065 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:38:00,087 n.b.c.j.i.JobBindingImpl INFO - getJobFromId Vacation
2014-01-16 14:38:00,092 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:38:00,115 n.b.c.j.i.JobBindingImpl INFO - getJobFromId EventAlert
2014-01-16 14:38:00,120 n.b.c.j.i.Scheduler INFO - Finishing Vacation-global.virt-1389883080093
2014-01-16 14:38:00,120 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883080093] progress is now 100%.
2014-01-16 14:38:00,120 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:38:00,121 n.b.c.j.i.ExecutionRecorder INFO - recording execution of Vacation-global.virt-1389883080093
2014-01-16 14:38:00,125 n.b.c.j.i.JobHome INFO - recording execution for Vacation-global.virt-1389883080093...
2014-01-16 14:38:00,137 n.b.c.j.i.JobHome INFO - run plan for Vacation@global.virt updated with last_run set to id 5 (start: Thu Jan 16 14:38:00 GMT 2014)
2014-01-16 14:38:00,137 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:38:00,147 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.archive.core.ArchiveJob
2014-01-16 14:38:00,147 n.b.c.j.i.Scheduler INFO - Finishing EventAlert-global.virt-1389883080122
2014-01-16 14:38:00,148 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883080122] progress is now 100%.
2014-01-16 14:38:00,149 n.b.c.j.i.ExecutionRecorder INFO - recording execution of EventAlert-global.virt-1389883080122
2014-01-16 14:38:00,153 n.b.c.j.i.JobHome INFO - recording execution for EventAlert-global.virt-1389883080122...
2014-01-16 14:38:00,153 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883080093] finished and recorded: SUCCESS, duration: 27ms.
2014-01-16 14:38:00,157 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:38:00,163 n.b.c.j.i.JobHome INFO - run plan for EventAlert@global.virt updated with last_run set to id 6 (start: Thu Jan 16 14:38:00 GMT 2014)
2014-01-16 14:38:00,164 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:38:00,180 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883080122] finished and recorded: SUCCESS, duration: 26ms.
2014-01-16 14:38:00,184 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.dataprotect.core.DataProtectJob
2014-01-16 14:38:00,188 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:38:00,190 n.b.d.c.DataProtectJob INFO - not planned, last run was 188secs ago.
2014-01-16 14:38:00,191 n.b.d.c.DataProtectJob INFO - less than one day ago, skipping execution.
2014-01-16 14:38:00,207 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.quota.QuotaGatheringJob
2014-01-16 14:38:00,211 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:39:00,032 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.cleanup.JobExecutionsCleanJob
2014-01-16 14:39:00,161 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:39:00,182 n.b.c.j.i.JobBindingImpl INFO - getJobFromId Vacation
2014-01-16 14:39:00,294 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:39:00,332 n.b.c.j.i.JobBindingImpl INFO - getJobFromId EventAlert
2014-01-16 14:39:00,339 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:39:00,356 n.b.c.j.i.Scheduler INFO - Finishing Vacation-global.virt-1389883140296
2014-01-16 14:39:00,357 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883140296] progress is now 100%.
2014-01-16 14:39:00,357 n.b.c.j.i.ExecutionRecorder INFO - recording execution of Vacation-global.virt-1389883140296
2014-01-16 14:39:00,361 n.b.c.j.i.JobHome INFO - recording execution for Vacation-global.virt-1389883140296...
2014-01-16 14:39:00,372 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.archive.core.ArchiveJob
2014-01-16 14:39:00,374 n.b.c.j.i.JobHome INFO - run plan for Vacation@global.virt updated with last_run set to id 7 (start: Thu Jan 16 14:39:00 GMT 2014)
2014-01-16 14:39:00,374 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:39:00,379 n.b.c.j.i.Scheduler INFO - Finishing EventAlert-global.virt-1389883140347
2014-01-16 14:39:00,379 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883140347] progress is now 100%.
2014-01-16 14:39:00,380 n.b.c.j.i.ExecutionRecorder INFO - recording execution of EventAlert-global.virt-1389883140347
2014-01-16 14:39:00,386 n.b.c.j.i.JobHome INFO - recording execution for EventAlert-global.virt-1389883140347...
2014-01-16 14:39:00,392 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:39:00,398 n.b.c.j.i.JobHome INFO - run plan for EventAlert@global.virt updated with last_run set to id 8 (start: Thu Jan 16 14:39:00 GMT 2014)
2014-01-16 14:39:00,400 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883140296] finished and recorded: SUCCESS, duration: 60ms.
2014-01-16 14:39:00,400 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:39:00,417 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883140347] finished and recorded: SUCCESS, duration: 32ms.
2014-01-16 14:39:00,427 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.dataprotect.core.DataProtectJob
2014-01-16 14:39:00,435 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:39:00,438 n.b.d.c.DataProtectJob INFO - not planned, last run was 248secs ago.
2014-01-16 14:39:00,439 n.b.d.c.DataProtectJob INFO - less than one day ago, skipping execution.
2014-01-16 14:39:00,463 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.quota.QuotaGatheringJob
2014-01-16 14:39:00,467 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:40:00,033 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.cleanup.JobExecutionsCleanJob
2014-01-16 14:40:00,037 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:40:00,061 n.b.c.j.i.JobBindingImpl INFO - getJobFromId Vacation
2014-01-16 14:40:00,066 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:40:00,093 n.b.c.j.i.JobBindingImpl INFO - getJobFromId EventAlert
2014-01-16 14:40:00,097 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:40:00,116 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.archive.core.ArchiveJob
2014-01-16 14:40:00,116 n.b.c.j.i.Scheduler INFO - Finishing EventAlert-global.virt-1389883200099
2014-01-16 14:40:00,117 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883200099] progress is now 100%.
2014-01-16 14:40:00,118 n.b.c.j.i.ExecutionRecorder INFO - recording execution of EventAlert-global.virt-1389883200099
2014-01-16 14:40:00,122 n.b.c.j.i.JobHome INFO - recording execution for EventAlert-global.virt-1389883200099...
2014-01-16 14:40:00,122 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:40:00,132 n.b.c.j.i.JobHome INFO - run plan for EventAlert@global.virt updated with last_run set to id 9 (start: Thu Jan 16 14:40:00 GMT 2014)
2014-01-16 14:40:00,133 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:40:00,147 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.dataprotect.core.DataProtectJob
2014-01-16 14:40:00,151 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:40:00,153 n.b.d.c.DataProtectJob INFO - not planned, last run was 308secs ago.
2014-01-16 14:40:00,153 n.b.d.c.DataProtectJob INFO - less than one day ago, skipping execution.
2014-01-16 14:40:00,161 n.b.c.j.i.Scheduler INFO - Finishing Vacation-global.virt-1389883200067
2014-01-16 14:40:00,162 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883200067] progress is now 100%.
2014-01-16 14:40:00,163 n.b.c.j.i.ExecutionRecorder INFO - recording execution of Vacation-global.virt-1389883200067
2014-01-16 14:40:00,167 n.b.c.j.i.JobHome INFO - recording execution for Vacation-global.virt-1389883200067...
2014-01-16 14:40:00,170 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883200099] finished and recorded: SUCCESS, duration: 18ms.
2014-01-16 14:40:00,171 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.quota.QuotaGatheringJob
2014-01-16 14:40:00,175 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:40:00,178 n.b.c.j.i.JobHome INFO - run plan for Vacation@global.virt updated with last_run set to id 10 (start: Thu Jan 16 14:40:00 GMT 2014)
2014-01-16 14:40:00,178 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:40:00,194 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883200067] finished and recorded: SUCCESS, duration: 95ms.
2014-01-16 14:41:00,031 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.cleanup.JobExecutionsCleanJob
2014-01-16 14:41:00,035 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:41:00,061 n.b.c.j.i.JobBindingImpl INFO - getJobFromId Vacation
2014-01-16 14:41:00,066 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:41:00,088 n.b.c.j.i.JobBindingImpl INFO - getJobFromId EventAlert
2014-01-16 14:41:00,093 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:41:00,114 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.archive.core.ArchiveJob
2014-01-16 14:41:00,119 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:41:00,122 n.b.c.j.i.Scheduler INFO - Finishing EventAlert-global.virt-1389883260094
2014-01-16 14:41:00,122 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883260094] progress is now 100%.
2014-01-16 14:41:00,123 n.b.c.j.i.ExecutionRecorder INFO - recording execution of EventAlert-global.virt-1389883260094
2014-01-16 14:41:00,125 n.b.c.j.i.Scheduler INFO - Finishing Vacation-global.virt-1389883260067
2014-01-16 14:41:00,126 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883260067] progress is now 100%.
2014-01-16 14:41:00,128 n.b.c.j.i.JobHome INFO - recording execution for EventAlert-global.virt-1389883260094...
2014-01-16 14:41:00,128 n.b.c.j.i.ExecutionRecorder INFO - recording execution of Vacation-global.virt-1389883260067
2014-01-16 14:41:00,137 n.b.c.j.i.JobHome INFO - recording execution for Vacation-global.virt-1389883260067...
2014-01-16 14:41:00,141 n.b.c.j.i.JobHome INFO - run plan for EventAlert@global.virt updated with last_run set to id 11 (start: Thu Jan 16 14:41:00 GMT 2014)
2014-01-16 14:41:00,142 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:41:00,149 n.b.c.j.i.JobHome INFO - run plan for Vacation@global.virt updated with last_run set to id 12 (start: Thu Jan 16 14:41:00 GMT 2014)
2014-01-16 14:41:00,150 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:41:00,150 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.dataprotect.core.DataProtectJob
2014-01-16 14:41:00,155 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:41:00,157 n.b.d.c.DataProtectJob INFO - not planned, last run was 368secs ago.
2014-01-16 14:41:00,157 n.b.d.c.DataProtectJob INFO - less than one day ago, skipping execution.
2014-01-16 14:41:00,167 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883260094] finished and recorded: SUCCESS, duration: 28ms.
2014-01-16 14:41:00,173 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.quota.QuotaGatheringJob
2014-01-16 14:41:00,175 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883260067] finished and recorded: SUCCESS, duration: 59ms.
2014-01-16 14:41:00,177 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:42:00,031 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.cleanup.JobExecutionsCleanJob
2014-01-16 14:42:00,035 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:42:00,056 n.b.c.j.i.JobBindingImpl INFO - getJobFromId Vacation
2014-01-16 14:42:00,061 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:42:00,086 n.b.c.j.i.JobBindingImpl INFO - getJobFromId EventAlert
2014-01-16 14:42:00,090 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:42:00,111 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.archive.core.ArchiveJob
2014-01-16 14:42:00,115 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:42:00,117 n.b.c.j.i.Scheduler INFO - Finishing Vacation-global.virt-1389883320062
2014-01-16 14:42:00,117 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883320062] progress is now 100%.
2014-01-16 14:42:00,118 n.b.c.j.i.ExecutionRecorder INFO - recording execution of Vacation-global.virt-1389883320062
2014-01-16 14:42:00,122 n.b.c.j.i.JobHome INFO - recording execution for Vacation-global.virt-1389883320062...
2014-01-16 14:42:00,123 n.b.c.j.i.Scheduler INFO - Finishing EventAlert-global.virt-1389883320091
2014-01-16 14:42:00,124 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883320091] progress is now 100%.
2014-01-16 14:42:00,127 n.b.c.j.i.ExecutionRecorder INFO - recording execution of EventAlert-global.virt-1389883320091
2014-01-16 14:42:00,131 n.b.c.j.i.JobHome INFO - recording execution for EventAlert-global.virt-1389883320091...
2014-01-16 14:42:00,132 n.b.c.j.i.JobHome INFO - run plan for Vacation@global.virt updated with last_run set to id 13 (start: Thu Jan 16 14:42:00 GMT 2014)
2014-01-16 14:42:00,132 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:42:00,141 n.b.c.j.i.JobHome INFO - run plan for EventAlert@global.virt updated with last_run set to id 14 (start: Thu Jan 16 14:42:00 GMT 2014)
2014-01-16 14:42:00,141 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:42:00,148 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.dataprotect.core.DataProtectJob
2014-01-16 14:42:00,152 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:42:00,154 n.b.d.c.DataProtectJob INFO - not planned, last run was 428secs ago.
2014-01-16 14:42:00,154 n.b.d.c.DataProtectJob INFO - less than one day ago, skipping execution.
2014-01-16 14:42:00,167 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883320062] finished and recorded: SUCCESS, duration: 55ms.
2014-01-16 14:42:00,169 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.quota.QuotaGatheringJob
2014-01-16 14:42:00,174 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:42:00,175 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883320091] finished and recorded: SUCCESS, duration: 33ms.
2014-01-16 14:43:00,030 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.cleanup.JobExecutionsCleanJob
2014-01-16 14:43:00,051 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:43:00,073 n.b.c.j.i.JobBindingImpl INFO - getJobFromId Vacation
2014-01-16 14:43:00,077 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:43:00,100 n.b.c.j.i.JobBindingImpl INFO - getJobFromId EventAlert
2014-01-16 14:43:00,106 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:43:00,123 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.archive.core.ArchiveJob
2014-01-16 14:43:00,126 n.b.c.j.i.Scheduler INFO - Finishing EventAlert-global.virt-1389883380107
2014-01-16 14:43:00,127 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:43:00,128 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883380107] progress is now 100%.
2014-01-16 14:43:00,129 n.b.c.j.i.ExecutionRecorder INFO - recording execution of EventAlert-global.virt-1389883380107
2014-01-16 14:43:00,133 n.b.c.j.i.JobHome INFO - recording execution for EventAlert-global.virt-1389883380107...
2014-01-16 14:43:00,144 n.b.c.j.i.JobHome INFO - run plan for EventAlert@global.virt updated with last_run set to id 15 (start: Thu Jan 16 14:43:00 GMT 2014)
2014-01-16 14:43:00,144 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:43:00,153 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.dataprotect.core.DataProtectJob
2014-01-16 14:43:00,160 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:43:00,161 n.b.c.j.i.Scheduler INFO - Finishing Vacation-global.virt-1389883380078
2014-01-16 14:43:00,162 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883380078] progress is now 100%.
2014-01-16 14:43:00,162 n.b.c.j.i.ExecutionRecorder INFO - recording execution of Vacation-global.virt-1389883380078
2014-01-16 14:43:00,164 n.b.d.c.DataProtectJob INFO - not planned, last run was 488secs ago.
2014-01-16 14:43:00,164 n.b.d.c.DataProtectJob INFO - less than one day ago, skipping execution.
2014-01-16 14:43:00,165 n.b.c.j.i.JobHome INFO - recording execution for Vacation-global.virt-1389883380078...
2014-01-16 14:43:00,169 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883380107] finished and recorded: SUCCESS, duration: 21ms.
2014-01-16 14:43:00,172 n.b.c.j.i.JobHome INFO - run plan for Vacation@global.virt updated with last_run set to id 16 (start: Thu Jan 16 14:43:00 GMT 2014)
2014-01-16 14:43:00,176 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:43:00,188 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883380078] finished and recorded: SUCCESS, duration: 83ms.
2014-01-16 14:43:00,189 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.quota.QuotaGatheringJob
2014-01-16 14:43:00,194 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:44:00,030 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.cleanup.JobExecutionsCleanJob
2014-01-16 14:44:00,070 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:44:00,091 n.b.c.j.i.JobBindingImpl INFO - getJobFromId Vacation
2014-01-16 14:44:00,096 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:44:00,117 n.b.c.j.i.JobBindingImpl INFO - getJobFromId EventAlert
2014-01-16 14:44:00,122 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:44:00,143 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.archive.core.ArchiveJob
2014-01-16 14:44:00,147 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:44:00,150 n.b.c.j.i.Scheduler INFO - Finishing EventAlert-global.virt-1389883440124
2014-01-16 14:44:00,150 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883440124] progress is now 100%.
2014-01-16 14:44:00,151 n.b.c.j.i.ExecutionRecorder INFO - recording execution of EventAlert-global.virt-1389883440124
2014-01-16 14:44:00,155 n.b.c.j.i.JobHome INFO - recording execution for EventAlert-global.virt-1389883440124...
2014-01-16 14:44:00,162 n.b.c.j.i.JobHome INFO - run plan for EventAlert@global.virt updated with last_run set to id 17 (start: Thu Jan 16 14:44:00 GMT 2014)
2014-01-16 14:44:00,162 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:44:00,171 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.dataprotect.core.DataProtectJob
2014-01-16 14:44:00,175 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:44:00,177 n.b.d.c.DataProtectJob INFO - not planned, last run was 548secs ago.
2014-01-16 14:44:00,177 n.b.d.c.DataProtectJob INFO - less than one day ago, skipping execution.
2014-01-16 14:44:00,183 n.b.c.j.i.Scheduler INFO - Finishing Vacation-global.virt-1389883440097
2014-01-16 14:44:00,184 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883440097] progress is now 100%.
2014-01-16 14:44:00,185 n.b.c.j.i.ExecutionRecorder INFO - recording execution of Vacation-global.virt-1389883440097
2014-01-16 14:44:00,190 n.b.c.j.i.JobHome INFO - recording execution for Vacation-global.virt-1389883440097...
2014-01-16 14:44:00,192 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883440124] finished and recorded: SUCCESS, duration: 26ms.
2014-01-16 14:44:00,195 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.quota.QuotaGatheringJob
2014-01-16 14:44:00,196 n.b.c.j.i.JobHome INFO - run plan for Vacation@global.virt updated with last_run set to id 18 (start: Thu Jan 16 14:44:00 GMT 2014)
2014-01-16 14:44:00,197 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:44:00,199 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:44:00,210 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883440097] finished and recorded: SUCCESS, duration: 87ms.
2014-01-16 14:45:00,031 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.cleanup.JobExecutionsCleanJob
2014-01-16 14:45:00,046 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:45:00,065 n.b.c.j.i.JobBindingImpl INFO - getJobFromId Vacation
2014-01-16 14:45:00,070 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:45:00,093 n.b.c.j.i.JobBindingImpl INFO - getJobFromId EventAlert
2014-01-16 14:45:00,097 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:45:00,117 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.archive.core.ArchiveJob
2014-01-16 14:45:00,122 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:45:00,135 n.b.c.j.i.Scheduler INFO - Finishing EventAlert-global.virt-1389883500099
2014-01-16 14:45:00,135 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883500099] progress is now 100%.
2014-01-16 14:45:00,136 n.b.c.j.i.ExecutionRecorder INFO - recording execution of EventAlert-global.virt-1389883500099
2014-01-16 14:45:00,140 n.b.c.j.i.JobHome INFO - recording execution for EventAlert-global.virt-1389883500099...
2014-01-16 14:45:00,146 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.dataprotect.core.DataProtectJob
2014-01-16 14:45:00,149 n.b.c.j.i.JobHome INFO - run plan for EventAlert@global.virt updated with last_run set to id 19 (start: Thu Jan 16 14:45:00 GMT 2014)
2014-01-16 14:45:00,150 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:45:00,151 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:45:00,153 n.b.d.c.DataProtectJob INFO - not planned, last run was 608secs ago.
2014-01-16 14:45:00,154 n.b.d.c.DataProtectJob INFO - less than one day ago, skipping execution.
2014-01-16 14:45:00,171 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.quota.QuotaGatheringJob
2014-01-16 14:45:00,174 n.b.c.j.i.Scheduler INFO - Finishing Vacation-global.virt-1389883500071
2014-01-16 14:45:00,175 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883500071] progress is now 100%.
2014-01-16 14:45:00,175 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:45:00,176 n.b.c.j.i.ExecutionRecorder INFO - recording execution of Vacation-global.virt-1389883500071
2014-01-16 14:45:00,178 n.b.c.j.i.JobHome INFO - recording execution for Vacation-global.virt-1389883500071...
2014-01-16 14:45:00,183 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883500099] finished and recorded: SUCCESS, duration: 36ms.
2014-01-16 14:45:00,185 n.b.c.j.i.JobHome INFO - run plan for Vacation@global.virt updated with last_run set to id 20 (start: Thu Jan 16 14:45:00 GMT 2014)
2014-01-16 14:45:00,186 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:45:00,280 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883500071] finished and recorded: SUCCESS, duration: 104ms.
2014-01-16 14:46:00,031 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.cleanup.JobExecutionsCleanJob
2014-01-16 14:46:00,051 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:46:00,077 n.b.c.j.i.JobBindingImpl INFO - getJobFromId Vacation
2014-01-16 14:46:00,082 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:46:00,107 n.b.c.j.i.JobBindingImpl INFO - getJobFromId EventAlert
2014-01-16 14:46:00,110 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:46:00,129 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.archive.core.ArchiveJob
2014-01-16 14:46:00,137 n.b.c.j.i.Scheduler INFO - Finishing EventAlert-global.virt-1389883560112
2014-01-16 14:46:00,138 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883560112] progress is now 100%.
2014-01-16 14:46:00,138 n.b.c.j.i.ExecutionRecorder INFO - recording execution of EventAlert-global.virt-1389883560112
2014-01-16 14:46:00,139 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:46:00,142 n.b.c.j.i.JobHome INFO - recording execution for EventAlert-global.virt-1389883560112...
2014-01-16 14:46:00,151 n.b.c.j.i.JobHome INFO - run plan for EventAlert@global.virt updated with last_run set to id 21 (start: Thu Jan 16 14:46:00 GMT 2014)
2014-01-16 14:46:00,151 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:46:00,156 n.b.c.j.i.Scheduler INFO - Finishing Vacation-global.virt-1389883560083
2014-01-16 14:46:00,157 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883560083] progress is now 100%.
2014-01-16 14:46:00,158 n.b.c.j.i.ExecutionRecorder INFO - recording execution of Vacation-global.virt-1389883560083
2014-01-16 14:46:00,161 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.dataprotect.core.DataProtectJob
2014-01-16 14:46:00,162 n.b.c.j.i.JobHome INFO - recording execution for Vacation-global.virt-1389883560083...
2014-01-16 14:46:00,166 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:46:00,168 n.b.d.c.DataProtectJob INFO - not planned, last run was 668secs ago.
2014-01-16 14:46:00,168 n.b.d.c.DataProtectJob INFO - less than one day ago, skipping execution.
2014-01-16 14:46:00,169 n.b.c.j.i.JobHome INFO - run plan for Vacation@global.virt updated with last_run set to id 22 (start: Thu Jan 16 14:46:00 GMT 2014)
2014-01-16 14:46:00,170 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:46:00,183 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.quota.QuotaGatheringJob
2014-01-16 14:46:00,187 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:46:00,196 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883560112] finished and recorded: SUCCESS, duration: 25ms.
2014-01-16 14:46:00,277 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883560083] finished and recorded: SUCCESS, duration: 74ms.
2014-01-16 14:47:00,031 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.cleanup.JobExecutionsCleanJob
2014-01-16 14:47:00,048 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:47:00,068 n.b.c.j.i.JobBindingImpl INFO - getJobFromId Vacation
2014-01-16 14:47:00,073 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:47:00,094 n.b.c.j.i.JobBindingImpl INFO - getJobFromId EventAlert
2014-01-16 14:47:00,098 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:47:00,102 n.b.c.j.i.Scheduler INFO - Finishing Vacation-global.virt-1389883620074
2014-01-16 14:47:00,105 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883620074] progress is now 100%.
2014-01-16 14:47:00,106 n.b.c.j.i.ExecutionRecorder INFO - recording execution of Vacation-global.virt-1389883620074
2014-01-16 14:47:00,109 n.b.c.j.i.JobHome INFO - recording execution for Vacation-global.virt-1389883620074...
2014-01-16 14:47:00,119 n.b.c.j.i.JobHome INFO - run plan for Vacation@global.virt updated with last_run set to id 23 (start: Thu Jan 16 14:47:00 GMT 2014)
2014-01-16 14:47:00,119 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.archive.core.ArchiveJob
2014-01-16 14:47:00,119 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:47:00,124 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:47:00,132 n.b.c.j.i.Scheduler INFO - Finishing EventAlert-global.virt-1389883620100
2014-01-16 14:47:00,133 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883620100] progress is now 100%.
2014-01-16 14:47:00,133 n.b.c.j.i.ExecutionRecorder INFO - recording execution of EventAlert-global.virt-1389883620100
2014-01-16 14:47:00,136 n.b.c.j.i.JobHome INFO - recording execution for EventAlert-global.virt-1389883620100...
2014-01-16 14:47:00,138 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883620074] finished and recorded: SUCCESS, duration: 31ms.
2014-01-16 14:47:00,144 n.b.c.j.i.JobHome INFO - run plan for EventAlert@global.virt updated with last_run set to id 24 (start: Thu Jan 16 14:47:00 GMT 2014)
2014-01-16 14:47:00,145 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:47:00,147 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.dataprotect.core.DataProtectJob
2014-01-16 14:47:00,151 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:47:00,153 n.b.d.c.DataProtectJob INFO - not planned, last run was 728secs ago.
2014-01-16 14:47:00,154 n.b.d.c.DataProtectJob INFO - less than one day ago, skipping execution.
2014-01-16 14:47:00,160 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883620100] finished and recorded: SUCCESS, duration: 33ms.
2014-01-16 14:47:00,174 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.quota.QuotaGatheringJob
2014-01-16 14:47:00,179 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:48:00,031 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.cleanup.JobExecutionsCleanJob
2014-01-16 14:48:00,052 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:48:00,072 n.b.c.j.i.JobBindingImpl INFO - getJobFromId Vacation
2014-01-16 14:48:00,077 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:48:00,101 n.b.c.j.i.JobBindingImpl INFO - getJobFromId EventAlert
2014-01-16 14:48:00,106 n.b.c.j.i.JobHome INFO - Loaded 1 jobs from database.
2014-01-16 14:48:00,107 n.b.c.j.i.Scheduler INFO - Finishing Vacation-global.virt-1389883680078
2014-01-16 14:48:00,108 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883680078] progress is now 100%.
2014-01-16 14:48:00,109 n.b.c.j.i.ExecutionRecorder INFO - recording execution of Vacation-global.virt-1389883680078
2014-01-16 14:48:00,112 n.b.c.j.i.JobHome INFO - recording execution for Vacation-global.virt-1389883680078...
2014-01-16 14:48:00,119 n.b.c.j.i.JobHome INFO - run plan for Vacation@global.virt updated with last_run set to id 25 (start: Thu Jan 16 14:48:00 GMT 2014)
2014-01-16 14:48:00,120 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:48:00,133 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.archive.core.ArchiveJob
2014-01-16 14:48:00,138 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:48:00,141 n.b.c.j.i.Scheduler INFO - [Vacation-global.virt-1389883680078] finished and recorded: SUCCESS, duration: 30ms.
2014-01-16 14:48:00,142 n.b.c.j.i.Scheduler INFO - Finishing EventAlert-global.virt-1389883680107
2014-01-16 14:48:00,142 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883680107] progress is now 100%.
2014-01-16 14:48:00,144 n.b.c.j.i.ExecutionRecorder INFO - recording execution of EventAlert-global.virt-1389883680107
2014-01-16 14:48:00,148 n.b.c.j.i.JobHome INFO - recording execution for EventAlert-global.virt-1389883680107...
2014-01-16 14:48:00,156 n.b.c.j.i.JobHome INFO - run plan for EventAlert@global.virt updated with last_run set to id 26 (start: Thu Jan 16 14:48:00 GMT 2014)
2014-01-16 14:48:00,156 n.b.c.j.i.JobHome INFO - saving 3 entries...
2014-01-16 14:48:00,163 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.dataprotect.core.DataProtectJob
2014-01-16 14:48:00,167 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
2014-01-16 14:48:00,168 n.b.d.c.DataProtectJob INFO - not planned, last run was 788secs ago.
2014-01-16 14:48:00,169 n.b.d.c.DataProtectJob INFO - less than one day ago, skipping execution.
2014-01-16 14:48:00,174 n.b.c.j.i.Scheduler INFO - [EventAlert-global.virt-1389883680107] finished and recorded: SUCCESS, duration: 35ms.
2014-01-16 14:48:00,183 n.b.c.j.i.JobBindingImpl INFO - getJobFromId net.bluemind.core.jobs.quota.QuotaGatheringJob
2014-01-16 14:48:00,187 n.b.c.j.i.JobHome INFO - Loaded 0 jobs from database.
Encore merci d’avance
Cordialement
