Alfresco: Fedora 24 (Server Edition)- Alfresco Community v5.1.0
LDAP: Windows 2008R2
The problem: default attributes (First name, last name, mail) from LDAP are synchronized. But additional Alfresco attributes (Mobile or Telephone) are missing. Problem is that ldap-authentication-context.xml and common-ldap-context.xml is missing frim 5.1 install.
Tried https://forums.alfresco.com/forum/installation-upgrades-configuration-integration/authentication-lda...,
Placed /opt/alfresco-community/tomcat/shared/classes/alfresco/extension/subsystems/Authentication/ldap-ad/myldap/authentication-context.xml and
/opt/alfresco-community/tomcat/shared/classes/alfresco/extension/subsystems/Authentication/common-ldap-context.xml from alfresco-repository-5.1.g.jar.
Added /opt/alfresco-community/tomcat/shared/classes/alfresco/extension/subsystems/Authentication/ldap-ad/ldap-authentication.properties
added
<entry key="cm:mobile">
<value>${ldap.synchronization.userMobileAttributeName}</value>
</entry>
into common-ldap-context.xml , but nothing happens
###############################################
###############################
## Common Alfresco Properties #
###############################
dir.root=/opt/alfresco-community/alf_data
alfresco.context=alfresco
alfresco.host=127.0.0.1
alfresco.port=8080
alfresco.protocol=http
share.context=share
share.host=127.0.0.1
share.port=8080
share.protocol=http
### database connection properties ###
db.driver=org.postgresql.Driver
db.username=alfresco
db.password=admin
db.name=alfresco
db.url=jdbc
ostgresql://localhost:5432/${db.name}
# Note: your database must also be able to accept at least this many connections. Please see your database documentation for instructions on how to configure this.
db.pool.max=275
db.pool.validate.query=SELECT 1
# The server mode. Set value here
# UNKNOWN | TEST | BACKUP | PRODUCTION
system.serverMode=UNKNOWN
### FTP Server Configuration ###
ftp.port=21
### RMI registry port for JMX ###
alfresco.rmi.services.port=50500
### External executable locations ###
ooo.exe=@@BITROCK_LIBREOFFICE_PROGRAM_DIR@@
ooo.enabled=true
ooo.port=8100
img.root=/opt/alfresco-community/common
img.dyn=${img.root}/lib
img.exe=${img.root}/bin/convert
jodconverter.enabled=false
jodconverter.officeHome=
jodconverter.portNumbers=8100
### Initial admin password ###
alfresco_user_store.adminpassword=209c6174da490caeb422f3fa5a7ae634
### E-mail site invitation setting ###
notification.email.siteinvite=false
### License location ###
dir.license.external=/opt/alfresco-community
### Solr indexing ###
index.subsystem.name=solr4
dir.keystore=${dir.root}/keystore
solr.host=localhost
solr.port.ssl=8443
### Allow extended ResultSet processing
security.anyDenyDenies=false
### Smart Folders Config Properties ###
smart.folders.enabled=false
### Remote JMX (Default: disabled) ###
alfresco.jmx.connector.enabled=false
authentication.chain=alfinst:alfrescoNtlm,myldap:ldap-ad
ntlm.authentication.sso.enabled=false
###############################################################
###############################################################
###############################################################
ldap-authentication.properties
ldap.authentication.active=true
ldap.authentication.allowGuestLogin=false
ldap.authentication.userNameFormat=%s@alfresco.testing.com
ldap.authentication.java.naming.provider.url=ldap://10.2.7.59:389
ldap.synchronization.java.naming.security.principal=CN=Administrator,CN=Users,DC=alfresco,DC=testing,DC=com
ldap.synchronization.java.naming.security.credentials=NME2016+
ldap.synchronization.active=true
ldap.synchronization.groupSearchBase=CN=Domain Users,CN=Users,DC=alfresco,DC=testing,DC=com
ldap.synchronization.userSearchBase=CN=Users,DC=alfresco,DC=testing,DC=com
ldap.synchronization.userIdAttributeName=sAMAccountName
ldap.synchronization.userFirstNameAttributeName=name
ldap.synchronization.userLastNameAttributeName=sn
ldap.synchronization.userEmailAttributeName=userPrincipalName
# The attribute on person objects in LDAP to map to the telephone property in Alfresco
ldap.synchronization.userCompanyTelephoneAttributeName=mobile
ldap.synchronization.userMobileAttributeName=mobile
ldap.synchronization.groupIdAttributeName=cn
ldap.synchronization.groupType=group
ldap.synchronization.personType=user
###############################################################
###############################################################
###############################################################
Catalina.out
2016-08-18 14:31:49,348 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Replication' subsystem, ID: [Replication, default]
2016-08-18 14:31:49,353 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Replication' subsystem, ID: [Replication, default]
2016-08-18 14:31:49,420 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Subscriptions' subsystem, ID: [Subscriptions, default]
2016-08-18 14:31:49,422 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Subscriptions' subsystem, ID: [Subscriptions, default]
2016-08-18 14:31:49,422 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'email' subsystem, ID: [email, inbound]
2016-08-18 14:31:49,423 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'email' subsystem, ID: [email, inbound]
2016-08-18 14:31:49,424 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'fileServers' subsystem, ID: [fileServers, default]
2016-08-18 14:31:50,140 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'fileServers' subsystem, ID: [fileServers, default]
2016-08-18 14:31:50,153 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2016-08-18 14:31:50,155 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2016-08-18 14:31:50,157 WARN [factory.support.DisposableBeanAdapter] [localhost-startStop-2] Invocation of destroy method 'close' failed on bean with name 'solrSqlSessionTemplate': java.lang.UnsupportedOperationException: Manual close is not allowed over a Spring managed SqlSession
2016-08-18 14:31:50,159 WARN [factory.support.DisposableBeanAdapter] [localhost-startStop-2] Invocation of destroy method 'close' failed on bean with name 'activitiesSqlSessionTemplate': java.lang.UnsupportedOperationException: Manual close is not allowed over a Spring managed SqlSession
2016-08-18 14:31:50,162 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'OOoDirect' subsystem, ID: [OOoDirect, default]
2016-08-18 14:31:50,163 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'OOoDirect' subsystem, ID: [OOoDirect, default]
2016-08-18 14:31:50,164 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2016-08-18 14:31:50,164 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2016-08-18 14:31:50,167 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Synchronization' subsystem, ID: [Synchronization, default]
2016-08-18 14:31:50,168 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Synchronization' subsystem, ID: [Synchronization, default]
2016-08-18 14:31:50,184 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'email' subsystem, ID: [email, outbound]
2016-08-18 14:31:50,188 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'email' subsystem, ID: [email, outbound]
2016-08-18 14:31:50,204 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'imap' subsystem, ID: [imap, default]
2016-08-18 14:31:50,207 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'imap' subsystem, ID: [imap, default]
2016-08-18 14:31:50,714 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Search' subsystem, ID: [Search, managed, solr4]
2016-08-18 14:31:51,162 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Search' subsystem, ID: [Search, managed, solr4]
2016-08-18 14:31:51,242 WARN [factory.support.DisposableBeanAdapter] [localhost-startStop-2] Invocation of destroy method 'close' failed on bean with name 'locksSqlSessionTemplate': java.lang.UnsupportedOperationException: Manual close is not allowed over a Spring managed SqlSession
2016-08-18 14:31:51,245 WARN [factory.support.DisposableBeanAdapter] [localhost-startStop-2] Invocation of destroy method 'close' failed on bean with name 'propertyValueSqlSessionTemplate': java.lang.UnsupportedOperationException: Manual close is not allowed over a Spring managed SqlSession
2016-08-18 14:31:51,245 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'thirdparty' subsystem, ID: [thirdparty, default]
2016-08-18 14:31:51,246 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'thirdparty' subsystem, ID: [thirdparty, default]
2016-08-18 14:31:51,247 WARN [factory.support.DisposableBeanAdapter] [localhost-startStop-2] Invocation of destroy method 'close' failed on bean with name 'auditSqlSessionTemplate': java.lang.UnsupportedOperationException: Manual close is not allowed over a Spring managed SqlSession
2016-08-18 14:31:51,248 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Authentication' subsystem, ID: [Authentication, managed, alfinst]
2016-08-18 14:31:51,248 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Authentication' subsystem, ID: [Authentication, managed, alfinst]
2016-08-18 14:31:51,249 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Authentication' subsystem, ID: [Authentication, managed, myldap]
2016-08-18 14:31:51,249 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Authentication' subsystem, ID: [Authentication, managed, myldap]
2016-08-18 14:31:51,249 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'sysAdmin' subsystem, ID: [sysAdmin, default]
2016-08-18 14:31:51,250 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'sysAdmin' subsystem, ID: [sysAdmin, default]
2016-08-18 14:31:51,250 WARN [factory.support.DisposableBeanAdapter] [localhost-startStop-2] Invocation of destroy method 'close' failed on bean with name 'contentSqlSessionTemplate': java.lang.UnsupportedOperationException: Manual close is not allowed over a Spring managed SqlSession
2016-08-18 14:31:51,253 WARN [factory.support.DisposableBeanAdapter] [localhost-startStop-2] Invocation of destroy method 'close' failed on bean with name 'repoSqlSessionTemplate': java.lang.UnsupportedOperationException: Manual close is not allowed over a Spring managed SqlSession
Aug 18, 2016 2:31:51 PM org.apache.catalina.core.ApplicationContext log
INFO: Shutting down log4j
Aug 18, 2016 2:31:51 PM org.apache.catalina.core.ApplicationContext log
INFO: Destroying Spring FrameworkServlet 'Spring Surf Dispatcher Servlet'
Aug 18, 2016 2:31:51 PM org.apache.catalina.core.ApplicationContext log
INFO: org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: destroy called
Aug 18, 2016 2:31:51 PM org.apache.catalina.core.ApplicationContext log
INFO: Closing Spring root WebApplicationContext
Aug 18, 2016 2:32:17 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-apr-8080"]
Aug 18, 2016 2:32:18 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["ajp-apr-8009"]
Aug 18, 2016 2:32:18 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-8443"]
Aug 18, 2016 2:32:18 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-apr-8080"]
Aug 18, 2016 2:32:18 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["ajp-apr-8009"]
Aug 18, 2016 2:32:18 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-8443"]
Aug 18, 2016 2:32:47 PM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: Loaded APR based Apache Tomcat Native library 1.1.32 using APR version 1.4.6.
Aug 18, 2016 2:32:47 PM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true].
Aug 18, 2016 2:32:47 PM org.apache.catalina.core.AprLifecycleListener initializeSSL
INFO: OpenSSL successfully initialized (OpenSSL 1.0.1m 19 Mar 2015)
Aug 18, 2016 2:32:47 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-apr-8080"]
Aug 18, 2016 2:32:47 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["ajp-apr-8009"]
Aug 18, 2016 2:32:47 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8443"]
Aug 18, 2016 2:32:48 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 1575 ms
Aug 18, 2016 2:32:48 PM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Aug 18, 2016 2:32:48 PM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.x
Aug 18, 2016 2:32:48 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor /opt/alfresco-community/tomcat/conf/Catalina/localhost/solr4.xml
Aug 18, 2016 2:32:48 PM org.apache.catalina.startup.SetContextPropertiesRule begin
WARNING: [SetContextPropertiesRule]{Context} Setting property 'debug' to '0' did not find a matching property.
Aug 18, 2016 2:32:58 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
2016-08-18 14:33:02,892 INFO [solr.component.AsyncBuildSuggestComponent] [coreLoadExecutor-5-thread-1] Initializing SuggestComponent
2016-08-18 14:33:02,892 INFO [solr.component.AsyncBuildSuggestComponent] [coreLoadExecutor-5-thread-2] Initializing SuggestComponent
2016-08-18 14:33:03,242 INFO [solr.component.AsyncBuildSuggestComponent] [Suggestor-alfresco-1] Loading suggester index for: shingleBasedSuggestions
Aug 18, 2016 2:33:03 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deployment of configuration descriptor /opt/alfresco-community/tomcat/conf/Catalina/localhost/solr4.xml has finished in 14,926 ms
Aug 18, 2016 2:33:03 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor /opt/alfresco-community/tomcat/conf/Catalina/localhost/share.xml
2016-08-18 14:33:04,029 INFO [solr.component.AsyncBuildSuggestComponent] [Suggestor-alfresco-1] Loaded suggester shingleBasedSuggestions, took 783 ms
Aug 18, 2016 2:33:06 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Aug 18, 2016 2:33:06 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2016-08-18 14:33:14,244 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2016-08-18 14:33:14,252 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2016-08-18 14:33:14,255 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2016-08-18 14:33:14,258 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2016-08-18 14:33:16,491 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 385 Web Scripts (+0 failed), 403 URLs
2016-08-18 14:33:16,491 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 8 Package Description Documents (+0 failed)
2016-08-18 14:33:16,491 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2016-08-18 14:33:16,653 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Surf Container Web Script Container (in 2343.8206ms)
2016-08-18 14:33:16,658 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2016-08-18 14:33:16,661 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
Aug 18, 2016 2:33:16 PM org.apache.catalina.core.ApplicationContext log
INFO: org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: loaded (conf ok)
Aug 18, 2016 2:33:17 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring FrameworkServlet 'Spring Surf Dispatcher Servlet'
Aug 18, 2016 2:33:17 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deployment of configuration descriptor /opt/alfresco-community/tomcat/conf/Catalina/localhost/share.xml has finished in 13,706 ms
Aug 18, 2016 2:33:17 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor /opt/alfresco-community/tomcat/conf/Catalina/localhost/alfresco.xml
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core_rt is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/core is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/fmt_rt is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/fmt is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/fmt is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/functions is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/standard/permittedTaglibs is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/standard/scriptfree is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/sql_rt is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/sql is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/sql is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/xml_rt is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/xml is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/xml is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Aug 18, 2016 2:33:28 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2016-08-18 14:33:36,938 INFO [alfresco.repo.admin] [localhost-startStop-1] Using database URL 'jdbcostgresql://localhost:5432/alfresco' with user 'alfresco'.
2016-08-18 14:33:36,944 INFO [alfresco.repo.admin] [localhost-startStop-1] Connected to database PostgreSQL version 9.4.4
2016-08-18 14:33:43,057 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Ignoring script patch (post-Hibernate): patch.db-V4.2-metadata-query-indexes
2016-08-18 14:33:43,059 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Ignoring script patch (post-Hibernate): patch.db-V5.1-metadata-query-indexes
2016-08-18 14:33:54,185 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor Repository Template Processor for extension ftl
2016-08-18 14:33:54,191 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor Repository Script Processor for extension js
2016-08-18 14:33:56,937 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2016-08-18 14:33:57,007 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted] complete
2016-08-18 14:33:57,062 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Connecting to database: jdbcostgresql://localhost:5432/alfresco, UserName=alfresco, PostgreSQL Native Driver
2016-08-18 14:33:57,064 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Schema managed by database dialect org.hibernate.dialect.PostgreSQLDialect.
2016-08-18 14:34:00,292 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] No changes were made to the schema.
2016-08-18 14:34:00,816 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfinst]
2016-08-18 14:34:00,999 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfinst] complete
2016-08-18 14:34:01,000 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Authentication' subsystem, ID: [Authentication, managed, myldap]
2016-08-18 14:34:01,163 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Authentication' subsystem, ID: [Authentication, managed, myldap] complete
2016-08-18 14:34:02,230 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
2016-08-18 14:34:02,429 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
2016-08-18 14:34:02,431 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
2016-08-18 14:34:02,792 ERROR [util.exec.RuntimeExecBootstrapBean] [localhost-startStop-1] Bootstrap command failed:
Execution result:
os: Linux
command: @@BITROCK_LIBREOFFICE_PROGRAM_DIR@@ -accept=socket,host=127.0.0.1,port=8100;urp;StarOffice.ServiceManager -env:UserInstallation=file:///opt/alfresco-community/alf_data/oouser -headless -nocrashreport -nofirststartwizard -nologo -norestore
succeeded: false
exit code: 2
out:
err: Cannot run program "@@BITROCK_LIBREOFFICE_PROGRAM_DIR@@": error=2, No such file or directory
2016-08-18 14:34:02,841 WARN [alfresco.util.OpenOfficeConnectionTester] [localhost-startStop-1] An initial OpenOffice connection could not be established.
2016-08-18 14:34:02,845 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
2016-08-18 14:34:02,848 INFO [repo.admin.ConfigurationChecker] [localhost-startStop-1] The Alfresco root data directory ('dir.root') is: /opt/alfresco-community/alf_data
2016-08-18 14:34:02,849 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Search' subsystem, ID: [Search, managed, solr4]
2016-08-18 14:34:03,336 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Search' subsystem, ID: [Search, managed, solr4] complete
2016-08-18 14:34:03,366 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Checking for patches to apply …
2016-08-18 14:34:03,789 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] No patches were required.
2016-08-18 14:34:03,824 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Found 1 module package(s).
2016-08-18 14:34:03,881 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Starting module 'alfresco-share-services' version 5.2.0.
2016-08-18 14:34:03,897 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'fileServers' subsystem, ID: [fileServers, default]
2016-08-18 14:34:04,335 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
2016-08-18 14:34:04,371 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
2016-08-18 14:34:04,464 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
2016-08-18 14:34:04,471 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'imap' subsystem, ID: [imap, default]
2016-08-18 14:34:04,618 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'imap' subsystem, ID: [imap, default] complete
2016-08-18 14:34:04,618 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'email' subsystem, ID: [email, outbound]
2016-08-18 14:34:04,966 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'email' subsystem, ID: [email, outbound] complete
2016-08-18 14:34:04,967 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'email' subsystem, ID: [email, inbound]
2016-08-18 14:34:05,043 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'email' subsystem, ID: [email, inbound] complete
2016-08-18 14:34:05,044 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Subscriptions' subsystem, ID: [Subscriptions, default]
2016-08-18 14:34:05,081 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Subscriptions' subsystem, ID: [Subscriptions, default] complete
2016-08-18 14:34:05,115 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
2016-08-18 14:34:05,162 DEBUG [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] Running a differential sync.
2016-08-18 14:34:05,162 DEBUG [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] deletions are allowed
2016-08-18 14:34:05,261 INFO [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] Synchronizing users and groups with user registry 'myldap'
2016-08-18 14:34:05,356 INFO [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] Retrieving groups changed since Aug 10, 2016 12:24:15 PM from user registry 'myldap'
2016-08-18 14:34:05,373 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Processing query
2016-08-18 14:34:05,374 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Search base: CN=Domain Users,CN=Users,DC=alfresco,DC=testing,DC=com
2016-08-18 14:34:05,375 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Return result limit: 0
2016-08-18 14:34:05,377 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] DerefLink: false
2016-08-18 14:34:05,379 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Return named object: false
2016-08-18 14:34:05,380 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Time limit for search: 0
2016-08-18 14:34:05,382 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Attributes to return: 4 items.
2016-08-18 14:34:05,383 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Attribute: cn
2016-08-18 14:34:05,383 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Attribute: displayName
2016-08-18 14:34:05,384 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Attribute: member;range=0-999
2016-08-18 14:34:05,385 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Attribute: whenChanged
2016-08-18 14:34:05,396 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Found 0
2016-08-18 14:34:05,406 INFO [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] Synchronization,Category=directory,id1=myldap,id2=1 Group Analysis: Commencing batch of 0 entries
2016-08-18 14:34:05,408 INFO [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] Synchronization,Category=directory,id1=myldap,id2=1 Group Analysis: Completed batch of 0 entries
2016-08-18 14:34:05,423 INFO [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] Retrieving users changed since Aug 18, 2016 11:37:56 AM from user registry 'myldap'
2016-08-18 14:34:05,427 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Processing query
2016-08-18 14:34:05,428 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Search base: CN=Users,DC=alfresco,DC=testing,DC=com
2016-08-18 14:34:05,430 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Return result limit: 0
2016-08-18 14:34:05,431 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] DerefLink: false
2016-08-18 14:34:05,432 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Return named object: false
2016-08-18 14:34:05,433 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Time limit for search: 0
2016-08-18 14:34:05,434 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Attributes to return: 0 items.
2016-08-18 14:34:05,441 INFO [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] Synchronization,Category=directory,id1=myldap,id2=6 User Creation and Association: Commencing batch of 0 entries
2016-08-18 14:34:05,443 INFO [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] Synchronization,Category=directory,id1=myldap,id2=6 User Creation and Association: Completed batch of 0 entries
2016-08-18 14:34:05,466 INFO [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] Finished synchronizing users and groups with user registry 'myldap'
2016-08-18 14:34:05,468 INFO [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] 0 user(s) and 0 group(s) processed
2016-08-18 14:34:05,513 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
2016-08-18 14:34:05,557 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco JVM - v1.8.0_65-b17; maximum heap size 2950.750MB
2016-08-18 14:34:05,559 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Server Mode :UNKNOWN
2016-08-18 14:34:05,560 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco started (Community). Current version: 5.1.0 (r127059-b7) schema 10,001. Originally installed version: 5.1.0 (r127059-b7) schema 10,001.
2016-08-18 14:34:05,577 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2016-08-18 14:34:06,047 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default] complete
2016-08-18 14:34:06,047 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Replication' subsystem, ID: [Replication, default]
2016-08-18 14:34:06,080 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Replication' subsystem, ID: [Replication, default] complete
2016-08-18 14:34:12,553 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 396 Web Scripts (+0 failed), 546 URLs
2016-08-18 14:34:12,559 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2016-08-18 14:34:12,559 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2016-08-18 14:34:14,353 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 396 Web Scripts (+0 failed), 546 URLs
2016-08-18 14:34:14,355 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 1 Package Description Documents (+0 failed)
2016-08-18 14:34:14,355 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2016-08-18 14:34:14,359 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Repository Web Script Container (in 5574.7534ms)
2016-08-18 14:34:14,391 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2016-08-18 14:34:14,396 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2016-08-18 14:34:16,213 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 396 Web Scripts (+0 failed), 546 URLs
2016-08-18 14:34:16,213 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2016-08-18 14:34:16,213 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2016-08-18 14:34:16,295 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 11 Web Scripts (+0 failed), 82 URLs
2016-08-18 14:34:16,297 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed)
2016-08-18 14:34:16,299 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2016-08-18 14:34:16,363 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 11 Web Scripts (+0 failed), 82 URLs
2016-08-18 14:34:16,364 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Package Description Documents (+0 failed)
2016-08-18 14:34:16,366 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2016-08-18 14:34:16,371 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Public Api Web Script Container (in 1678.9366ms)
2016-08-18 14:34:16,411 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 11 Web Scripts (+0 failed), 82 URLs
2016-08-18 14:34:16,413 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed)
2016-08-18 14:34:16,414 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
Aug 18, 2016 2:34:16 PM com.sun.xml.ws.transport.http.servlet.WSServletContextListener contextInitialized
INFO: WSSERVLET12: JAX-WS context listener initializing
Aug 18, 2016 2:34:21 PM com.sun.xml.ws.transport.http.servlet.WSServletDelegate <init>
INFO: WSSERVLET14: JAX-WS servlet initializing
Aug 18, 2016 2:34:21 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deployment of configuration descriptor /opt/alfresco-community/tomcat/conf/Catalina/localhost/alfresco.xml has finished in 64,545 ms
Aug 18, 2016 2:34:21 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /opt/alfresco-community/tomcat/webapps/ROOT.war
Aug 18, 2016 2:34:21 PM org.apache.catalina.startup.SetContextPropertiesRule begin
WARNING: [SetContextPropertiesRule]{Context} Setting property 'debug' to '100' did not find a matching property.
Aug 18, 2016 2:34:21 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /opt/alfresco-community/tomcat/webapps/ROOT.war has finished in 105 ms
Aug 18, 2016 2:34:21 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /opt/alfresco-community/tomcat/webapps/manager
Aug 18, 2016 2:34:21 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /opt/alfresco-community/tomcat/webapps/manager has finished in 90 ms
Aug 18, 2016 2:34:21 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /opt/alfresco-community/tomcat/webapps/host-manager
Aug 18, 2016 2:34:21 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /opt/alfresco-community/tomcat/webapps/host-manager has finished in 80 ms
Aug 18, 2016 2:34:21 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-apr-8080"]
Aug 18, 2016 2:34:21 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["ajp-apr-8009"]
Aug 18, 2016 2:34:21 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8443"]
Aug 18, 2016 2:34:21 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 93591 ms
2016-08-18 14:34:26,888 INFO [web.site.EditionInterceptor] [http-apr-8080-exec-3] Successfully retrieved license information from Alfresco.
2016-08-18 14:34:35,505 INFO [web.scripts.ImapServerStatus] [http-apr-8080-exec-1] Successfully retrieved IMAP server status from Alfresco: disabled
LDAP: Windows 2008R2
The problem: default attributes (First name, last name, mail) from LDAP are synchronized. But additional Alfresco attributes (Mobile or Telephone) are missing. Problem is that ldap-authentication-context.xml and common-ldap-context.xml is missing frim 5.1 install.
Tried https://forums.alfresco.com/forum/installation-upgrades-configuration-integration/authentication-lda...,
Placed /opt/alfresco-community/tomcat/shared/classes/alfresco/extension/subsystems/Authentication/ldap-ad/myldap/authentication-context.xml and
/opt/alfresco-community/tomcat/shared/classes/alfresco/extension/subsystems/Authentication/common-ldap-context.xml from alfresco-repository-5.1.g.jar.
Added /opt/alfresco-community/tomcat/shared/classes/alfresco/extension/subsystems/Authentication/ldap-ad/ldap-authentication.properties
added
<entry key="cm:mobile">
<value>${ldap.synchronization.userMobileAttributeName}</value>
</entry>
into common-ldap-context.xml , but nothing happens
###############################################
###############################
## Common Alfresco Properties #
###############################
dir.root=/opt/alfresco-community/alf_data
alfresco.context=alfresco
alfresco.host=127.0.0.1
alfresco.port=8080
alfresco.protocol=http
share.context=share
share.host=127.0.0.1
share.port=8080
share.protocol=http
### database connection properties ###
db.driver=org.postgresql.Driver
db.username=alfresco
db.password=admin
db.name=alfresco
db.url=jdbc
ostgresql://localhost:5432/${db.name}# Note: your database must also be able to accept at least this many connections. Please see your database documentation for instructions on how to configure this.
db.pool.max=275
db.pool.validate.query=SELECT 1
# The server mode. Set value here
# UNKNOWN | TEST | BACKUP | PRODUCTION
system.serverMode=UNKNOWN
### FTP Server Configuration ###
ftp.port=21
### RMI registry port for JMX ###
alfresco.rmi.services.port=50500
### External executable locations ###
ooo.exe=@@BITROCK_LIBREOFFICE_PROGRAM_DIR@@
ooo.enabled=true
ooo.port=8100
img.root=/opt/alfresco-community/common
img.dyn=${img.root}/lib
img.exe=${img.root}/bin/convert
jodconverter.enabled=false
jodconverter.officeHome=
jodconverter.portNumbers=8100
### Initial admin password ###
alfresco_user_store.adminpassword=209c6174da490caeb422f3fa5a7ae634
### E-mail site invitation setting ###
notification.email.siteinvite=false
### License location ###
dir.license.external=/opt/alfresco-community
### Solr indexing ###
index.subsystem.name=solr4
dir.keystore=${dir.root}/keystore
solr.host=localhost
solr.port.ssl=8443
### Allow extended ResultSet processing
security.anyDenyDenies=false
### Smart Folders Config Properties ###
smart.folders.enabled=false
### Remote JMX (Default: disabled) ###
alfresco.jmx.connector.enabled=false
authentication.chain=alfinst:alfrescoNtlm,myldap:ldap-ad
ntlm.authentication.sso.enabled=false
###############################################################
###############################################################
###############################################################
ldap-authentication.properties
ldap.authentication.active=true
ldap.authentication.allowGuestLogin=false
ldap.authentication.userNameFormat=%s@alfresco.testing.com
ldap.authentication.java.naming.provider.url=ldap://10.2.7.59:389
ldap.synchronization.java.naming.security.principal=CN=Administrator,CN=Users,DC=alfresco,DC=testing,DC=com
ldap.synchronization.java.naming.security.credentials=NME2016+
ldap.synchronization.active=true
ldap.synchronization.groupSearchBase=CN=Domain Users,CN=Users,DC=alfresco,DC=testing,DC=com
ldap.synchronization.userSearchBase=CN=Users,DC=alfresco,DC=testing,DC=com
ldap.synchronization.userIdAttributeName=sAMAccountName
ldap.synchronization.userFirstNameAttributeName=name
ldap.synchronization.userLastNameAttributeName=sn
ldap.synchronization.userEmailAttributeName=userPrincipalName
# The attribute on person objects in LDAP to map to the telephone property in Alfresco
ldap.synchronization.userCompanyTelephoneAttributeName=mobile
ldap.synchronization.userMobileAttributeName=mobile
ldap.synchronization.groupIdAttributeName=cn
ldap.synchronization.groupType=group
ldap.synchronization.personType=user
###############################################################
###############################################################
###############################################################
Catalina.out
2016-08-18 14:31:49,348 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Replication' subsystem, ID: [Replication, default]
2016-08-18 14:31:49,353 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Replication' subsystem, ID: [Replication, default]
2016-08-18 14:31:49,420 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Subscriptions' subsystem, ID: [Subscriptions, default]
2016-08-18 14:31:49,422 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Subscriptions' subsystem, ID: [Subscriptions, default]
2016-08-18 14:31:49,422 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'email' subsystem, ID: [email, inbound]
2016-08-18 14:31:49,423 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'email' subsystem, ID: [email, inbound]
2016-08-18 14:31:49,424 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'fileServers' subsystem, ID: [fileServers, default]
2016-08-18 14:31:50,140 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'fileServers' subsystem, ID: [fileServers, default]
2016-08-18 14:31:50,153 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2016-08-18 14:31:50,155 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2016-08-18 14:31:50,157 WARN [factory.support.DisposableBeanAdapter] [localhost-startStop-2] Invocation of destroy method 'close' failed on bean with name 'solrSqlSessionTemplate': java.lang.UnsupportedOperationException: Manual close is not allowed over a Spring managed SqlSession
2016-08-18 14:31:50,159 WARN [factory.support.DisposableBeanAdapter] [localhost-startStop-2] Invocation of destroy method 'close' failed on bean with name 'activitiesSqlSessionTemplate': java.lang.UnsupportedOperationException: Manual close is not allowed over a Spring managed SqlSession
2016-08-18 14:31:50,162 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'OOoDirect' subsystem, ID: [OOoDirect, default]
2016-08-18 14:31:50,163 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'OOoDirect' subsystem, ID: [OOoDirect, default]
2016-08-18 14:31:50,164 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2016-08-18 14:31:50,164 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2016-08-18 14:31:50,167 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Synchronization' subsystem, ID: [Synchronization, default]
2016-08-18 14:31:50,168 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Synchronization' subsystem, ID: [Synchronization, default]
2016-08-18 14:31:50,184 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'email' subsystem, ID: [email, outbound]
2016-08-18 14:31:50,188 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'email' subsystem, ID: [email, outbound]
2016-08-18 14:31:50,204 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'imap' subsystem, ID: [imap, default]
2016-08-18 14:31:50,207 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'imap' subsystem, ID: [imap, default]
2016-08-18 14:31:50,714 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Search' subsystem, ID: [Search, managed, solr4]
2016-08-18 14:31:51,162 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Search' subsystem, ID: [Search, managed, solr4]
2016-08-18 14:31:51,242 WARN [factory.support.DisposableBeanAdapter] [localhost-startStop-2] Invocation of destroy method 'close' failed on bean with name 'locksSqlSessionTemplate': java.lang.UnsupportedOperationException: Manual close is not allowed over a Spring managed SqlSession
2016-08-18 14:31:51,245 WARN [factory.support.DisposableBeanAdapter] [localhost-startStop-2] Invocation of destroy method 'close' failed on bean with name 'propertyValueSqlSessionTemplate': java.lang.UnsupportedOperationException: Manual close is not allowed over a Spring managed SqlSession
2016-08-18 14:31:51,245 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'thirdparty' subsystem, ID: [thirdparty, default]
2016-08-18 14:31:51,246 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'thirdparty' subsystem, ID: [thirdparty, default]
2016-08-18 14:31:51,247 WARN [factory.support.DisposableBeanAdapter] [localhost-startStop-2] Invocation of destroy method 'close' failed on bean with name 'auditSqlSessionTemplate': java.lang.UnsupportedOperationException: Manual close is not allowed over a Spring managed SqlSession
2016-08-18 14:31:51,248 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Authentication' subsystem, ID: [Authentication, managed, alfinst]
2016-08-18 14:31:51,248 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Authentication' subsystem, ID: [Authentication, managed, alfinst]
2016-08-18 14:31:51,249 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Authentication' subsystem, ID: [Authentication, managed, myldap]
2016-08-18 14:31:51,249 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Authentication' subsystem, ID: [Authentication, managed, myldap]
2016-08-18 14:31:51,249 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'sysAdmin' subsystem, ID: [sysAdmin, default]
2016-08-18 14:31:51,250 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'sysAdmin' subsystem, ID: [sysAdmin, default]
2016-08-18 14:31:51,250 WARN [factory.support.DisposableBeanAdapter] [localhost-startStop-2] Invocation of destroy method 'close' failed on bean with name 'contentSqlSessionTemplate': java.lang.UnsupportedOperationException: Manual close is not allowed over a Spring managed SqlSession
2016-08-18 14:31:51,253 WARN [factory.support.DisposableBeanAdapter] [localhost-startStop-2] Invocation of destroy method 'close' failed on bean with name 'repoSqlSessionTemplate': java.lang.UnsupportedOperationException: Manual close is not allowed over a Spring managed SqlSession
Aug 18, 2016 2:31:51 PM org.apache.catalina.core.ApplicationContext log
INFO: Shutting down log4j
Aug 18, 2016 2:31:51 PM org.apache.catalina.core.ApplicationContext log
INFO: Destroying Spring FrameworkServlet 'Spring Surf Dispatcher Servlet'
Aug 18, 2016 2:31:51 PM org.apache.catalina.core.ApplicationContext log
INFO: org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: destroy called
Aug 18, 2016 2:31:51 PM org.apache.catalina.core.ApplicationContext log
INFO: Closing Spring root WebApplicationContext
Aug 18, 2016 2:32:17 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-apr-8080"]
Aug 18, 2016 2:32:18 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["ajp-apr-8009"]
Aug 18, 2016 2:32:18 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-8443"]
Aug 18, 2016 2:32:18 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-apr-8080"]
Aug 18, 2016 2:32:18 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["ajp-apr-8009"]
Aug 18, 2016 2:32:18 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-8443"]
Aug 18, 2016 2:32:47 PM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: Loaded APR based Apache Tomcat Native library 1.1.32 using APR version 1.4.6.
Aug 18, 2016 2:32:47 PM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true].
Aug 18, 2016 2:32:47 PM org.apache.catalina.core.AprLifecycleListener initializeSSL
INFO: OpenSSL successfully initialized (OpenSSL 1.0.1m 19 Mar 2015)
Aug 18, 2016 2:32:47 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-apr-8080"]
Aug 18, 2016 2:32:47 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["ajp-apr-8009"]
Aug 18, 2016 2:32:47 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8443"]
Aug 18, 2016 2:32:48 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 1575 ms
Aug 18, 2016 2:32:48 PM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Aug 18, 2016 2:32:48 PM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.x
Aug 18, 2016 2:32:48 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor /opt/alfresco-community/tomcat/conf/Catalina/localhost/solr4.xml
Aug 18, 2016 2:32:48 PM org.apache.catalina.startup.SetContextPropertiesRule begin
WARNING: [SetContextPropertiesRule]{Context} Setting property 'debug' to '0' did not find a matching property.
Aug 18, 2016 2:32:58 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
2016-08-18 14:33:02,892 INFO [solr.component.AsyncBuildSuggestComponent] [coreLoadExecutor-5-thread-1] Initializing SuggestComponent
2016-08-18 14:33:02,892 INFO [solr.component.AsyncBuildSuggestComponent] [coreLoadExecutor-5-thread-2] Initializing SuggestComponent
2016-08-18 14:33:03,242 INFO [solr.component.AsyncBuildSuggestComponent] [Suggestor-alfresco-1] Loading suggester index for: shingleBasedSuggestions
Aug 18, 2016 2:33:03 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deployment of configuration descriptor /opt/alfresco-community/tomcat/conf/Catalina/localhost/solr4.xml has finished in 14,926 ms
Aug 18, 2016 2:33:03 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor /opt/alfresco-community/tomcat/conf/Catalina/localhost/share.xml
2016-08-18 14:33:04,029 INFO [solr.component.AsyncBuildSuggestComponent] [Suggestor-alfresco-1] Loaded suggester shingleBasedSuggestions, took 783 ms
Aug 18, 2016 2:33:06 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Aug 18, 2016 2:33:06 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2016-08-18 14:33:14,244 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2016-08-18 14:33:14,252 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2016-08-18 14:33:14,255 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2016-08-18 14:33:14,258 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2016-08-18 14:33:16,491 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 385 Web Scripts (+0 failed), 403 URLs
2016-08-18 14:33:16,491 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 8 Package Description Documents (+0 failed)
2016-08-18 14:33:16,491 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2016-08-18 14:33:16,653 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Surf Container Web Script Container (in 2343.8206ms)
2016-08-18 14:33:16,658 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2016-08-18 14:33:16,661 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
Aug 18, 2016 2:33:16 PM org.apache.catalina.core.ApplicationContext log
INFO: org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: loaded (conf ok)
Aug 18, 2016 2:33:17 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring FrameworkServlet 'Spring Surf Dispatcher Servlet'
Aug 18, 2016 2:33:17 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deployment of configuration descriptor /opt/alfresco-community/tomcat/conf/Catalina/localhost/share.xml has finished in 13,706 ms
Aug 18, 2016 2:33:17 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor /opt/alfresco-community/tomcat/conf/Catalina/localhost/alfresco.xml
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core_rt is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/core is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/fmt_rt is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/fmt is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/fmt is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/functions is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/standard/permittedTaglibs is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/standard/scriptfree is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/sql_rt is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/sql is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/sql is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/xml_rt is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/xml is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/xml is already defined
Aug 18, 2016 2:33:28 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Aug 18, 2016 2:33:28 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2016-08-18 14:33:36,938 INFO [alfresco.repo.admin] [localhost-startStop-1] Using database URL 'jdbc
ostgresql://localhost:5432/alfresco' with user 'alfresco'.2016-08-18 14:33:36,944 INFO [alfresco.repo.admin] [localhost-startStop-1] Connected to database PostgreSQL version 9.4.4
2016-08-18 14:33:43,057 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Ignoring script patch (post-Hibernate): patch.db-V4.2-metadata-query-indexes
2016-08-18 14:33:43,059 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Ignoring script patch (post-Hibernate): patch.db-V5.1-metadata-query-indexes
2016-08-18 14:33:54,185 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor Repository Template Processor for extension ftl
2016-08-18 14:33:54,191 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor Repository Script Processor for extension js
2016-08-18 14:33:56,937 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2016-08-18 14:33:57,007 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted] complete
2016-08-18 14:33:57,062 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Connecting to database: jdbc
ostgresql://localhost:5432/alfresco, UserName=alfresco, PostgreSQL Native Driver2016-08-18 14:33:57,064 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Schema managed by database dialect org.hibernate.dialect.PostgreSQLDialect.
2016-08-18 14:34:00,292 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] No changes were made to the schema.
2016-08-18 14:34:00,816 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfinst]
2016-08-18 14:34:00,999 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfinst] complete
2016-08-18 14:34:01,000 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Authentication' subsystem, ID: [Authentication, managed, myldap]
2016-08-18 14:34:01,163 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Authentication' subsystem, ID: [Authentication, managed, myldap] complete
2016-08-18 14:34:02,230 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
2016-08-18 14:34:02,429 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
2016-08-18 14:34:02,431 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
2016-08-18 14:34:02,792 ERROR [util.exec.RuntimeExecBootstrapBean] [localhost-startStop-1] Bootstrap command failed:
Execution result:
os: Linux
command: @@BITROCK_LIBREOFFICE_PROGRAM_DIR@@ -accept=socket,host=127.0.0.1,port=8100;urp;StarOffice.ServiceManager -env:UserInstallation=file:///opt/alfresco-community/alf_data/oouser -headless -nocrashreport -nofirststartwizard -nologo -norestore
succeeded: false
exit code: 2
out:
err: Cannot run program "@@BITROCK_LIBREOFFICE_PROGRAM_DIR@@": error=2, No such file or directory
2016-08-18 14:34:02,841 WARN [alfresco.util.OpenOfficeConnectionTester] [localhost-startStop-1] An initial OpenOffice connection could not be established.
2016-08-18 14:34:02,845 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
2016-08-18 14:34:02,848 INFO [repo.admin.ConfigurationChecker] [localhost-startStop-1] The Alfresco root data directory ('dir.root') is: /opt/alfresco-community/alf_data
2016-08-18 14:34:02,849 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Search' subsystem, ID: [Search, managed, solr4]
2016-08-18 14:34:03,336 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Search' subsystem, ID: [Search, managed, solr4] complete
2016-08-18 14:34:03,366 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Checking for patches to apply …
2016-08-18 14:34:03,789 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] No patches were required.
2016-08-18 14:34:03,824 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Found 1 module package(s).
2016-08-18 14:34:03,881 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Starting module 'alfresco-share-services' version 5.2.0.
2016-08-18 14:34:03,897 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'fileServers' subsystem, ID: [fileServers, default]
2016-08-18 14:34:04,335 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
2016-08-18 14:34:04,371 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
2016-08-18 14:34:04,464 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
2016-08-18 14:34:04,471 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'imap' subsystem, ID: [imap, default]
2016-08-18 14:34:04,618 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'imap' subsystem, ID: [imap, default] complete
2016-08-18 14:34:04,618 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'email' subsystem, ID: [email, outbound]
2016-08-18 14:34:04,966 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'email' subsystem, ID: [email, outbound] complete
2016-08-18 14:34:04,967 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'email' subsystem, ID: [email, inbound]
2016-08-18 14:34:05,043 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'email' subsystem, ID: [email, inbound] complete
2016-08-18 14:34:05,044 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Subscriptions' subsystem, ID: [Subscriptions, default]
2016-08-18 14:34:05,081 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Subscriptions' subsystem, ID: [Subscriptions, default] complete
2016-08-18 14:34:05,115 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
2016-08-18 14:34:05,162 DEBUG [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] Running a differential sync.
2016-08-18 14:34:05,162 DEBUG [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] deletions are allowed
2016-08-18 14:34:05,261 INFO [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] Synchronizing users and groups with user registry 'myldap'
2016-08-18 14:34:05,356 INFO [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] Retrieving groups changed since Aug 10, 2016 12:24:15 PM from user registry 'myldap'
2016-08-18 14:34:05,373 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Processing query
2016-08-18 14:34:05,374 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Search base: CN=Domain Users,CN=Users,DC=alfresco,DC=testing,DC=com
2016-08-18 14:34:05,375 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Return result limit: 0
2016-08-18 14:34:05,377 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] DerefLink: false
2016-08-18 14:34:05,379 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Return named object: false
2016-08-18 14:34:05,380 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Time limit for search: 0
2016-08-18 14:34:05,382 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Attributes to return: 4 items.
2016-08-18 14:34:05,383 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Attribute: cn
2016-08-18 14:34:05,383 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Attribute: displayName
2016-08-18 14:34:05,384 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Attribute: member;range=0-999
2016-08-18 14:34:05,385 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Attribute: whenChanged
2016-08-18 14:34:05,396 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Found 0
2016-08-18 14:34:05,406 INFO [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] Synchronization,Category=directory,id1=myldap,id2=1 Group Analysis: Commencing batch of 0 entries
2016-08-18 14:34:05,408 INFO [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] Synchronization,Category=directory,id1=myldap,id2=1 Group Analysis: Completed batch of 0 entries
2016-08-18 14:34:05,423 INFO [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] Retrieving users changed since Aug 18, 2016 11:37:56 AM from user registry 'myldap'
2016-08-18 14:34:05,427 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Processing query
2016-08-18 14:34:05,428 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Search base: CN=Users,DC=alfresco,DC=testing,DC=com
2016-08-18 14:34:05,430 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Return result limit: 0
2016-08-18 14:34:05,431 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] DerefLink: false
2016-08-18 14:34:05,432 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Return named object: false
2016-08-18 14:34:05,433 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Time limit for search: 0
2016-08-18 14:34:05,434 DEBUG [sync.ldap.LDAPUserRegistry] [localhost-startStop-1] Attributes to return: 0 items.
2016-08-18 14:34:05,441 INFO [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] Synchronization,Category=directory,id1=myldap,id2=6 User Creation and Association: Commencing batch of 0 entries
2016-08-18 14:34:05,443 INFO [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] Synchronization,Category=directory,id1=myldap,id2=6 User Creation and Association: Completed batch of 0 entries
2016-08-18 14:34:05,466 INFO [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] Finished synchronizing users and groups with user registry 'myldap'
2016-08-18 14:34:05,468 INFO [security.sync.ChainingUserRegistrySynchronizer] [localhost-startStop-1] 0 user(s) and 0 group(s) processed
2016-08-18 14:34:05,513 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
2016-08-18 14:34:05,557 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco JVM - v1.8.0_65-b17; maximum heap size 2950.750MB
2016-08-18 14:34:05,559 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Server Mode :UNKNOWN
2016-08-18 14:34:05,560 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco started (Community). Current version: 5.1.0 (r127059-b7) schema 10,001. Originally installed version: 5.1.0 (r127059-b7) schema 10,001.
2016-08-18 14:34:05,577 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2016-08-18 14:34:06,047 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default] complete
2016-08-18 14:34:06,047 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Replication' subsystem, ID: [Replication, default]
2016-08-18 14:34:06,080 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Replication' subsystem, ID: [Replication, default] complete
2016-08-18 14:34:12,553 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 396 Web Scripts (+0 failed), 546 URLs
2016-08-18 14:34:12,559 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2016-08-18 14:34:12,559 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2016-08-18 14:34:14,353 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 396 Web Scripts (+0 failed), 546 URLs
2016-08-18 14:34:14,355 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 1 Package Description Documents (+0 failed)
2016-08-18 14:34:14,355 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2016-08-18 14:34:14,359 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Repository Web Script Container (in 5574.7534ms)
2016-08-18 14:34:14,391 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2016-08-18 14:34:14,396 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2016-08-18 14:34:16,213 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 396 Web Scripts (+0 failed), 546 URLs
2016-08-18 14:34:16,213 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2016-08-18 14:34:16,213 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2016-08-18 14:34:16,295 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 11 Web Scripts (+0 failed), 82 URLs
2016-08-18 14:34:16,297 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed)
2016-08-18 14:34:16,299 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2016-08-18 14:34:16,363 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 11 Web Scripts (+0 failed), 82 URLs
2016-08-18 14:34:16,364 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Package Description Documents (+0 failed)
2016-08-18 14:34:16,366 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2016-08-18 14:34:16,371 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Public Api Web Script Container (in 1678.9366ms)
2016-08-18 14:34:16,411 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 11 Web Scripts (+0 failed), 82 URLs
2016-08-18 14:34:16,413 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed)
2016-08-18 14:34:16,414 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
Aug 18, 2016 2:34:16 PM com.sun.xml.ws.transport.http.servlet.WSServletContextListener contextInitialized
INFO: WSSERVLET12: JAX-WS context listener initializing
Aug 18, 2016 2:34:21 PM com.sun.xml.ws.transport.http.servlet.WSServletDelegate <init>
INFO: WSSERVLET14: JAX-WS servlet initializing
Aug 18, 2016 2:34:21 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deployment of configuration descriptor /opt/alfresco-community/tomcat/conf/Catalina/localhost/alfresco.xml has finished in 64,545 ms
Aug 18, 2016 2:34:21 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /opt/alfresco-community/tomcat/webapps/ROOT.war
Aug 18, 2016 2:34:21 PM org.apache.catalina.startup.SetContextPropertiesRule begin
WARNING: [SetContextPropertiesRule]{Context} Setting property 'debug' to '100' did not find a matching property.
Aug 18, 2016 2:34:21 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /opt/alfresco-community/tomcat/webapps/ROOT.war has finished in 105 ms
Aug 18, 2016 2:34:21 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /opt/alfresco-community/tomcat/webapps/manager
Aug 18, 2016 2:34:21 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /opt/alfresco-community/tomcat/webapps/manager has finished in 90 ms
Aug 18, 2016 2:34:21 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /opt/alfresco-community/tomcat/webapps/host-manager
Aug 18, 2016 2:34:21 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /opt/alfresco-community/tomcat/webapps/host-manager has finished in 80 ms
Aug 18, 2016 2:34:21 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-apr-8080"]
Aug 18, 2016 2:34:21 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["ajp-apr-8009"]
Aug 18, 2016 2:34:21 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8443"]
Aug 18, 2016 2:34:21 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 93591 ms
2016-08-18 14:34:26,888 INFO [web.site.EditionInterceptor] [http-apr-8080-exec-3] Successfully retrieved license information from Alfresco.
2016-08-18 14:34:35,505 INFO [web.scripts.ImapServerStatus] [http-apr-8080-exec-1] Successfully retrieved IMAP server status from Alfresco: disabled
