AnsweredAssumed Answered

Issue unable to import telephone attributes from LDAP users to Alfresco.

Question asked by depo on Aug 18, 2016
Latest reply on Aug 22, 2016 by depo
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-ldap-sso/missing-ldap-subsystem,
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:postgresql://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:postgresql://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:postgresql://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

Outcomes