topic Re: Create content of custom type Tutorial not working in Alfresco Archive

Create content of custom type Tutorial not working

anujcb — Tue, 14 Apr 2015 15:55:41 GMT

Hi, I was trying out the tutorial "Create content of custom type". When I run the sample I get the following error. I have community edition ver 5.When I go to the URL "http://localhost:8080/alfresco/service/modeltest/MyWhitepaper " I get the below exception. I am a newbie to Alfresco.ThanksAnilA

Re: Create content of custom type Tutorial not working

anujcb — Fri, 17 Apr 2015 05:54:47 GMT

Any pointers will be much appreciated!

Re: Create content of custom type Tutorial not working

sihnu — Fri, 17 Apr 2015 11:55:04 GMT

Could you post the content of your ftl template and probably description file as well (yourwebscriptname.get.desc.xml)?

Re: Create content of custom type Tutorial not working

mrogers — Fri, 17 Apr 2015 11:57:55 GMT

No help is possible from the few details that you have posted so far.

Re: Create content of custom type Tutorial not working

anujcb — Fri, 17 Apr 2015 15:26:00 GMT

modeltest.get.desc.xml



<webscript>
   <shortname>Model test</shortname>
   <family>Tutorials</family>
   <description>Creates content of a custom type</description>
   <url>/modeltest/{documentName}</url>
   <format default="html">extension</format>
   <authentication>user</authentication>
</webscript> 
‍‍‍‍‍‍‍‍‍‍‍

modeltest.get.html.ftl


<p>Creating the following document:</p>
<ul>
   <li>${document.name}</li>
   <li>${document.type}</li>
</ul>
<b>${msg}</b>
‍‍‍‍‍‍‍‍

modeltest.get.js


var contentType = "my:whitepaper";
var documentName = url.templateArgs.documentName;

var document = companyhome.createNode(documentName, contentType);

if (document != null){
   model.document = document;
   model.msg = "Created OK!";
}
else {
   model.msg = "Failed to create document!";
}
‍‍‍‍‍‍‍‍‍‍‍‍‍‍

custom-model-context.xml


<?xml version='1.0' encoding='UTF-8'?>
<!DOCTYPE beans PUBLIC '-//SPRING//DTD BEAN//EN' 'http://www.springframework.org/dtd/spring-beans.dtd'>
 
<beans>

    <!– Registration of new models –>   
    <bean id="custommodel.dictionaryBootstrap" parent="dictionaryModelBootstrap" depends-on="dictionaryBootstrap">
        <property name="models">
            <list>
                <value>alfresco/extension/customModel.xml</value>
            </list>
        </property>
    </bean>
          
</beans>
‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

customModel.xml


<?xml version="1.0" encoding="UTF-8"?>

<!– Definition of new Model –>

<!– The important part here is the name - Note: the use of the my: namespace 
   which is defined further on in the document –>
<model name="my:custommodel" xmlns="http://www.alfresco.org/model/dictionary/1.0">

   <!– Optional meta-data about the model –>
   <description>Example Custom Model</description>
   <author>Alfresco Documentation Team</author>
   <version>1.0</version>

   <!– Imports are required to allow references to definitions in other models –>
   <imports>
      <!– Import Alfresco Dictionary Definitions –>
      <import uri="http://www.alfresco.org/model/dictionary/1.0"
         prefix="d" />
      <!– Import Alfresco Content Domain Model Definitions –>
      <import uri="http://www.alfresco.org/model/content/1.0" prefix="cm" />
   </imports>

   <!– Introduction of new namespaces defined by this model –>
   <!– NOTE: The following namespace my.new.model should be changed to reflect 
      your own namespace –>
   <namespaces>
      <namespace uri="http://www.mycompany.com/model/content/1.0"
         prefix="my" />
   </namespaces>

   <constraints>
      <constraint name="my:productListConstraint" type="LIST">
         <parameter name="allowedValues">
            <list>
               <value>Foobar Widget</value>
               <value>Flux Capacitor</value>
               <value>Big Bold App</value>
            </list>
         </parameter>
      </constraint>
   </constraints>

   <types>
      <!– Enterprise-wide generic document type –>
      <type name="my:doc">
         <title>MyCompany Generic Document</title>
         <parent>cm:content</parent>
      </type>
      <type name="my:marketingDoc">
         <title>MyCompany Marketing Document</title>
         <parent>my:doc</parent>
         <properties>
            <property name="my:product">
               <type>d:text</type>
               <multiple>true</multiple>
               <constraints>
                  <constraint ref="my:productListConstraint" />
               </constraints>
            </property>
         </properties>
      </type>
      <type name="my:whitepaper">
         <title>MyCompany Whitepaper</title>
         <parent>my:marketingDoc</parent>
      </type>
   </types>
</model>                
‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

Re: Create content of custom type Tutorial not working

anujcb — Sat, 18 Apr 2015 07:16:18 GMT

I deleted the .js file and recreated it. Could have been some weird character in it.

Re: Create content of custom type Tutorial not working

vivekthakur21 — Mon, 17 Aug 2015 11:17:41 GMT

Same error or issue, i am getting today. My cataline.out file says -

Aug 17, 2015 1:40:49 PM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: /Users/vthakur/Library/Java/Extensions:/Library/Java/Extensions:/Network/Library/Java/Extensions:/System/Library/Java/Extensions:/usr/lib/java:.
Aug 17, 2015 1:40:49 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 1:40:49 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 1:40:49 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 1:40:49 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 471 ms
Aug 17, 2015 1:40:49 PM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Aug 17, 2015 1:40:49 PM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.59
Aug 17, 2015 1:40:49 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor /Applications/alfresco-5.0.1.5/tomcat/conf/Catalina/localhost/solr4.xml
Aug 17, 2015 1:40:49 PM org.apache.catalina.startup.SetContextPropertiesRule begin
WARNING: [SetContextPropertiesRule]{Context} Setting property 'debug' to '0' did not find a matching property.
Aug 17, 2015 1:40:53 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
2015-08-17 13:40:54,840 INFO [solr.component.AsyncBuildSuggestComponent] [coreLoadExecutor-5-thread-2] Initializing SuggestComponent
2015-08-17 13:40:54,840 INFO [solr.component.AsyncBuildSuggestComponent] [coreLoadExecutor-5-thread-1] Initializing SuggestComponent
2015-08-17 13:40:54,924 INFO [solr.component.AsyncBuildSuggestComponent] [Suggestor-alfresco-1] Loading suggester index for: shingleBasedSuggestions
2015-08-17 13:40:54,935 INFO [solr.component.AsyncBuildSuggestComponent] [Suggestor-alfresco-1] Loaded suggester shingleBasedSuggestions, took 10 ms
Aug 17, 2015 1:40:54 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deployment of configuration descriptor /Applications/alfresco-5.0.1.5/tomcat/conf/Catalina/localhost/solr4.xml has finished in 5,320 ms
Aug 17, 2015 1:40:54 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/_vti_bin.war
Aug 17, 2015 1:40:55 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/_vti_bin.war has finished in 74 ms
Aug 17, 2015 1:40:55 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/alfresco.war
Aug 17, 2015 1:41:00 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core_rt is already defined
Aug 17, 2015 1:41:00 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core is already defined
Aug 17, 2015 1:41:00 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/core is already defined
Aug 17, 2015 1:41:00 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/fmt_rt is already defined
Aug 17, 2015 1:41:00 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/fmt is already defined
Aug 17, 2015 1:41:00 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/fmt is already defined
Aug 17, 2015 1:41:00 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/functions is already defined
Aug 17, 2015 1:41:00 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/standard/permittedTaglibs is already defined
Aug 17, 2015 1:41:00 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/standard/scriptfree is already defined
Aug 17, 2015 1:41:00 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/sql_rt is already defined
Aug 17, 2015 1:41:00 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/sql is already defined
Aug 17, 2015 1:41:00 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/sql is already defined
Aug 17, 2015 1:41:00 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/xml_rt is already defined
Aug 17, 2015 1:41:00 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/xml is already defined
Aug 17, 2015 1:41:00 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/xml is already defined
Aug 17, 2015 1:41:00 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Aug 17, 2015 1:41:00 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2015-08-17 13:41:03,311 INFO [alfresco.repo.admin] [localhost-startStop-1] Using database URL 'jdbcostgresql://localhost:5432/alfresco' with user 'alfresco'.
2015-08-17 13:41:03,448 INFO [alfresco.repo.admin] [localhost-startStop-1] Connected to database PostgreSQL version 9.3.6
2015-08-17 13:41:07,353 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 13:41:07,378 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
2015-08-17 13:41:07,387 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Ignoring script patch (post-Hibernate): patch.db-V4.2-metadata-query-indexes
2015-08-17 13:41:12,348 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor Repository Template Processor for extension ftl
2015-08-17 13:41:12,350 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor Repository Script Processor for extension js
2015-08-17 13:41:13,770 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 13:41:13,803 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted] complete
2015-08-17 13:41:13,836 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Connecting to database: jdbcostgresql://localhost:5432/alfresco, UserName=alfresco, PostgreSQL Native Driver
2015-08-17 13:41:13,836 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Schema managed by database dialect org.hibernate.dialect.PostgreSQLDialect.
2015-08-17 13:41:13,901 WARN [domain.schema.SchemaBootstrap] [localhost-startStop-1] Schema validation found 83 potential problems, results written to: /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/Alfresco-PostgreSQLDialect-Validation-Pre-Upgrade-alf_-3826940159227613176.txt
2015-08-17 13:41:13,911 WARN [domain.schema.SchemaBootstrap] [localhost-startStop-1] Schema validation found 28 potential problems, results written to: /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/Alfresco-PostgreSQLDialect-Validation-Pre-Upgrade-jbpm_-3688433195156515609.txt
2015-08-17 13:41:13,921 WARN [domain.schema.SchemaBootstrap] [localhost-startStop-1] Schema validation found 25 potential problems, results written to: /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/Alfresco-PostgreSQLDialect-Validation-Pre-Upgrade-act_-5077024892309581050.txt
2015-08-17 13:41:13,979 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Normalized schema dumped to file /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/Alfresco-schema-PostgreSQLDialect-pre-upgrade-alf_-2659361196375369190.xml.
2015-08-17 13:41:13,979 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Normalized schema dumped to file /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/Alfresco-schema-PostgreSQLDialect-pre-upgrade-jbpm_-2330025405927670566.xml.
2015-08-17 13:41:13,980 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Normalized schema dumped to file /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/Alfresco-schema-PostgreSQLDialect-pre-upgrade-act_-1192591704732619633.xml.
2015-08-17 13:41:13,982 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Executing database script /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-7244849896188384903.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-RepoTables.sql).
2015-08-17 13:41:14,134 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Executing database script /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-8469879371803992104.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-LockTables.sql).
2015-08-17 13:41:14,147 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Executing database script /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-4730267994857237452.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-ContentTables.sql).
2015-08-17 13:41:14,176 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Executing database script /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-8606558376554544325.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-PropertyValueTables.sql).
2015-08-17 13:41:14,225 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Executing database script /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-431862852653342559.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-ContentUrlEncryptionTables.sql).
2015-08-17 13:41:14,233 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Executing database script /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-6678299659732607136.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-AuditTables.sql).
2015-08-17 13:41:14,259 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Executing database script /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-3892552823660653712.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-ActivityTables.sql).
2015-08-17 13:41:14,280 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Executing database script /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-910471791219474624.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-UsageTables.sql).
2015-08-17 13:41:14,286 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Executing database script /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-360711563792582709.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-SubscriptionTables.sql).
2015-08-17 13:41:14,291 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Executing database script /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-1196259507232521855.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-TenantTables.sql).
2015-08-17 13:41:14,393 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Executing database script /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-2219634393011878071.sql (Generated).
2015-08-17 13:41:14,589 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Executing database script /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-787115855343598164.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoPostCreate-JBPM-Extra.sql).
2015-08-17 13:41:14,591 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Executing database script /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-2789817146727316252.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoPostCreate-JBPM-FK-indexes.sql).
2015-08-17 13:41:14,666 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Executing database script /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-7141215488333161131.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoPostCreate-JBPM-varinst-indexes.sql).
2015-08-17 13:41:14,668 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Create scripts executed in 813 ms
2015-08-17 13:41:15,002 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] All executed statements: /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-All_Statements-196774730479567769.sql.
2015-08-17 13:41:17,133 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Compared database schema with reference schema (all OK): class path resource [alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/Schema-Reference-ALF.xml]
2015-08-17 13:41:18,405 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Compared database schema with reference schema (all OK): class path resource [alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/Schema-Reference-JBPM.xml]
2015-08-17 13:41:19,481 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Compared database schema with reference schema (all OK): class path resource [alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/Schema-Reference-ACT.xml]
2015-08-17 13:41:23,873 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Normalized schema dumped to file /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/Alfresco-schema-PostgreSQLDialect-post-upgrade-alf_-6065547931805081470.xml.
2015-08-17 13:41:23,873 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Normalized schema dumped to file /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/Alfresco-schema-PostgreSQLDialect-post-upgrade-jbpm_-5420918433228660980.xml.
2015-08-17 13:41:23,873 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Normalized schema dumped to file /Applications/alfresco-5.0.1.5/tomcat/temp/Alfresco/Alfresco-schema-PostgreSQLDialect-post-upgrade-act_-6361538662903579855.xml.
2015-08-17 13:41:24,274 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 13:41:24,463 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Search' subsystem, ID: [Search, managed, solr4] complete
2015-08-17 13:41:29,097 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 13:41:29,201 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
2015-08-17 13:41:30,386 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco license: Creating time limited trial license
2015-08-17 13:41:30,517 INFO [repo.sync.SyncAdminServiceImpl] [localhost-startStop-1] There is no key for cloud sync, cloud sync turned off
2015-08-17 13:41:30,526 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 13:41:30,595 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
2015-08-17 13:41:30,595 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 13:41:30,636 WARN [alfresco.util.AbstractTriggerBean] [localhost-startStop-1] Job openOfficeConnectionTesterTrigger is not enabled
2015-08-17 13:41:30,736 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
2015-08-17 13:41:30,736 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
Aug 17, 2015 1:41:30 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager <init>
INFO: ProcessManager implementation is PureJavaProcessManager
Aug 17, 2015 1:41:30 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Using GNU based LibreOffice command on Mac: [/Applications/alfresco-5.0.1.5/libreoffice.app/Contents/MacOS/soffice, –accept=socket,host=127.0.0.1,port=8100;urp;, -env:UserInstallation=file:///Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100, –headless, –nocrashreport, –nodefault, –nofirststartwizard, –nolockcheck, –nologo, –norestore]
Aug 17, 2015 1:41:30 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Using GNU based LibreOffice 3.6 command on Mac: [/Applications/alfresco-5.0.1.5/libreoffice.app/Contents/MacOS/soffice, –accept=socket,host=127.0.0.1,port=8100;urp;, -env:UserInstallation=file:///Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100, –headless, –nocrashreport, –nodefault, –nofirststartwizard, –nolockcheck, –nologo, –norestore]
Aug 17, 2015 1:41:30 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Removing $DYLD_LIBRARY_PATH from the environment so that LibreOffice/OpenOffice will start on Mac.
Aug 17, 2015 1:41:30 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: starting process with acceptString 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1' and profileDir '/Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100'
Aug 17, 2015 1:41:30 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: started process
Aug 17, 2015 1:41:32 PM org.artofsolving.jodconverter.office.OfficeConnection connect
INFO: connected: 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1'
2015-08-17 13:41:32,377 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default] complete
2015-08-17 13:41:32,379 WARN [cluster.core.ClusteringBootstrap] [localhost-startStop-1] License does not permit clustering: clustering is disabled.
2015-08-17 13:41:32,383 INFO [repo.admin.ConfigurationChecker] [localhost-startStop-1] The Alfresco root data directory ('dir.root') is: /Applications/alfresco-5.0.1.5/alf_data
2015-08-17 13:41:32,393 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Checking for patches to apply …
2015-08-17 13:41:32,475 WARN [admin.patch.PatchExecuter] [localhost-startStop-1] Patch description is not available: Patch[ id=patch.contentFormFolderType, description=patch.contentFormFolderType.description, fixesFromSchema=0, fixesToSchema=36, targetSchema=37, ignored=false]
2015-08-17 13:41:32,491 WARN [admin.patch.PatchExecuter] [localhost-startStop-1] Patch description is not available: Patch[ id=patch.redeploySubmitProcess, description=patch.redeploySubmitProcess.description, fixesFromSchema=0, fixesToSchema=57, targetSchema=58, ignored=false]
2015-08-17 13:41:32,770 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]    Applying patch 'patch.siteLoadPatch.swsdp' (Loads a sample site into the repository.).
2015-08-17 13:41:35,116 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]    Applying patch 'patch.exampleJavaScript' (Loads sample Javascript file into datadictionary scripts folder).
2015-08-17 13:41:35,163 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch                 ===
ID: patch.db-V3.4-UsageTables
RESULT:
Script completed
=====================================
2015-08-17 13:41:35,163 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch                 ===
ID: patch.db-V4.0-TenantTables
RESULT:
Script completed
=====================================
2015-08-17 13:41:35,163 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch                 ===
ID: patch.db-V3.4-JBPM-varinst-indexes
RESULT:
Script completed
=====================================
2015-08-17 13:41:35,163 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch                 ===
ID: patch.db-V5.0-ContentUrlEncryptionTables
RESULT:
Script completed
=====================================
2015-08-17 13:41:35,163 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch                 ===
ID: patch.siteLoadPatch.swsdp
RESULT:
Site swsdp imported.
=====================================
2015-08-17 13:41:35,163 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch                 ===
ID: patch.exampleJavaScript
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary/app:scripts (workspace://SpacesStore/058f5bcf-e122-4cc5-b0ac-8590287914da)
=====================================
2015-08-17 13:41:35,182 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Found 1 module(s).
2015-08-17 13:41:35,203 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Installing module 'org.alfresco.integrations.google.docs' version 3.0.0.
2015-08-17 13:41:35,217 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 13:41:35,456 WARN [org.alfresco.fileserver] [localhost-startStop-1] CIFS server name is longer than 15 characters, truncated to Administrators-
2015-08-17 13:41:35,466 WARN [org.alfresco.fileserver] [localhost-startStop-1] CIFS, Unable to get local domain/workgroup name, using default of WORKGROUP. This may be due to firewall settings or incorrect <broadcast> setting)
2015-08-17 13:41:35,471 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
2015-08-17 13:41:35,472 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'imap' subsystem, ID: [imap, default]
2015-08-17 13:41:35,486 ERROR [org.alfresco.fileserver] [CIFS Server] [SMB] Server error : org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
2015-08-17 13:41:35,489 ERROR [org.alfresco.fileserver] [CIFS Server] Error from JLAN
org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
   at org.alfresco.jlan.smb.server.nio.NIOCifsConnectionsHandler.initializeHandler(NIOCifsConnectionsHandler.java:259)
   at org.alfresco.jlan.smb.server.SMBServer.run(SMBServer.java:479)
   at java.lang.Thread.run(Thread.java:745)
2015-08-17 13:41:35,558 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'imap' subsystem, ID: [imap, default] complete
2015-08-17 13:41:35,559 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'email' subsystem, ID: [email, outbound]
2015-08-17 13:41:35,604 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'email' subsystem, ID: [email, outbound] complete
2015-08-17 13:41:35,604 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'email' subsystem, ID: [email, inbound]
2015-08-17 13:41:35,652 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'email' subsystem, ID: [email, inbound] complete
2015-08-17 13:41:35,652 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 13:41:35,664 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Subscriptions' subsystem, ID: [Subscriptions, default] complete
2015-08-17 13:41:35,678 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 13:41:35,747 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
2015-08-17 13:41:35,770 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco JVM - v1.8.0_31-b13; maximum heap size 8125.500MB
2015-08-17 13:41:35,779 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco license: Mode ENTERPRISE, NO CLUSTER granted to Trial User limited to 30 days expiring Wed Sep 16 00:00:00 IST 2015 (30 days remaining).
2015-08-17 13:41:35,779 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Server Mode :UNKNOWN
2015-08-17 13:41:35,779 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco started (Enterprise). Current version: 5.0.1 (.5 r108957-b87) schema 8,022. Originally installed version: 5.0.1 (.5 r108957-b87) schema 8,022.
2015-08-17 13:41:35,787 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 13:41:35,944 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default] complete
2015-08-17 13:41:35,944 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Replication' subsystem, ID: [Replication, default]
2015-08-17 13:41:35,955 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Replication' subsystem, ID: [Replication, default] complete
2015-08-17 13:41:36,834 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 13:41:36,899 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'googledocs' subsystem, ID: [googledocs, drive] complete
2015-08-17 13:41:38,890 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 13:41:38,891 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 13:41:38,891 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:41:40,050 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 13:41:40,050 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 13:41:40,050 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:41:40,051 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Repository Web Script Container (in 3125.4578ms)
2015-08-17 13:41:40,064 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 13:41:40,068 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 13:41:41,133 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 13:41:41,133 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 13:41:41,133 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:41:41,171 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 13:41:41,172 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 13:41:41,172 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:41:41,259 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 13:41:41,259 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 13:41:41,259 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:41:41,260 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Public Api Web Script Container (in 1156.049ms)
2015-08-17 13:41:41,290 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 13:41:41,290 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 13:41:41,290 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
Aug 17, 2015 1:41:41 PM com.sun.xml.ws.transport.http.servlet.WSServletContextListener contextInitialized
INFO: WSSERVLET12: JAX-WS context listener initializing
Aug 17, 2015 1:41:43 PM com.sun.xml.ws.transport.http.servlet.WSServletDelegate <init>
INFO: WSSERVLET14: JAX-WS servlet initializing
Aug 17, 2015 1:41:43 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/alfresco.war has finished in 48,666 ms
Aug 17, 2015 1:41:43 PM org.apache.catalina.core.StandardContext setPath
WARNING: A context path must either be an empty string or start with a '/' and do not end with a '/'. The path [/] does not meet these criteria and has been changed to []
Aug 17, 2015 1:41:43 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/ROOT.war
Aug 17, 2015 1:41:43 PM org.apache.catalina.startup.SetContextPropertiesRule begin
WARNING: [SetContextPropertiesRule]{Context} Setting property 'debug' to '100' did not find a matching property.
Aug 17, 2015 1:41:43 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/ROOT.war has finished in 59 ms
Aug 17, 2015 1:41:43 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/share.war
Aug 17, 2015 1:41:47 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Aug 17, 2015 1:41:47 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2015-08-17 13:41:51,048 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 13:41:51,051 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 13:41:51,051 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 13:41:51,052 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 13:41:51,957 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 369 Web Scripts (+0 failed), 383 URLs
2015-08-17 13:41:51,957 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 8 Package Description Documents (+0 failed)
2015-08-17 13:41:51,957 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:41:52,031 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Spring Surf Container Web Script Container (in 978.45374ms)
2015-08-17 13:41:52,057 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 13:41:52,058 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
Aug 17, 2015 1:41:52 PM org.apache.catalina.core.ApplicationContext log
INFO: org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: loaded (conf ok)
Aug 17, 2015 1:41:52 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring FrameworkServlet 'Spring Surf Dispatcher Servlet'
Aug 17, 2015 1:41:52 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/share.war has finished in 8,488 ms
Aug 17, 2015 1:41:52 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/host-manager
Aug 17, 2015 1:41:52 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/host-manager has finished in 41 ms
Aug 17, 2015 1:41:52 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/manager
Aug 17, 2015 1:41:52 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/manager has finished in 37 ms
Aug 17, 2015 1:41:52 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 1:41:52 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 1:41:52 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 1:41:52 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 62734 ms
2015-08-17 13:42:03,660 INFO [web.site.EditionInterceptor] [http-bio-8080-exec-4] Successfully retrieved license information from Alfresco.
2015-08-17 13:42:11,785 INFO [web.scripts.ImapServerStatus] [http-bio-8080-exec-4] Successfully retrieved IMAP server status from Alfresco: disabled
2015-08-17 13:42:11,923 INFO [web.scripts.SyncModeConfig] [http-bio-8080-exec-4] Successfully retrieved Sync Mode configuration from Alfresco: OFF
2015-08-17 13:42:15,067 INFO [management.subsystems.ChildApplicationContextFactory] [http-bio-8443-exec-3] Starting 'Transformers' subsystem, ID: [Transformers, default]
2015-08-17 13:42:15,319 INFO [management.subsystems.ChildApplicationContextFactory] [http-bio-8443-exec-3] Startup of 'Transformers' subsystem, ID: [Transformers, default] complete
2015-08-17 13:42:26,981 INFO [web.scripts.DictionaryQuery] [http-bio-8080-exec-4] Successfully retrieved Data Dictionary from Alfresco.
2015-08-17 13:44:05,017 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-6] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 13:44:05,017 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-6] Registered 1 Package Description Documents (+0 failed)
2015-08-17 13:44:05,017 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-6] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:44:05,018 INFO [extensions.webscripts.AbstractRuntimeContainer] [http-bio-8080-exec-6] Initialised Repository Web Script Container (in 1231.4344ms)
2015-08-17 13:44:06,123 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 13:44:06,123 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 13:44:06,123 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
Aug 17, 2015 1:52:36 PM org.apache.catalina.core.StandardServer await
INFO: A valid shutdown command was received via the shutdown port. Stopping the Server instance.
Aug 17, 2015 1:52:36 PM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 1:52:36 PM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 1:52:36 PM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 1:52:36 PM org.apache.catalina.core.StandardService stopInternal
INFO: Stopping service Catalina
Aug 17, 2015 1:52:36 PM com.sun.xml.ws.transport.http.servlet.WSServletDelegate destroy
INFO: WSSERVLET15: JAX-WS servlet destroyed
Aug 17, 2015 1:52:36 PM com.sun.xml.ws.transport.http.servlet.WSServletContextListener contextDestroyed
INFO: WSSERVLET13: JAX-WS context listener destroyed
Aug 17, 2015 1:52:36 PM org.apache.catalina.core.ApplicationContext log
INFO: Closing Spring root WebApplicationContext
2015-08-17 13:52:36,442 INFO [cluster.core.ClusteringBootstrap] [localhost-startStop-2] Clustering has shutdown.
2015-08-17 13:52:36,467 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 13:52:36,468 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 13:52:36,475 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Replication' subsystem, ID: [Replication, default]
2015-08-17 13:52:36,476 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Replication' subsystem, ID: [Replication, default]
2015-08-17 13:52:36,485 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 13:52:36,487 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 13:52:36,487 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'email' subsystem, ID: [email, inbound]
2015-08-17 13:52:36,488 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'email' subsystem, ID: [email, inbound]
2015-08-17 13:52:36,488 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 13:52:36,490 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 13:52:36,501 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
Aug 17, 2015 1:52:36 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager stop
INFO: stopping
Aug 17, 2015 1:52:36 PM org.artofsolving.jodconverter.office.OfficeConnection$1 disposing
INFO: disconnected: 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1'
Aug 17, 2015 1:52:36 PM org.artofsolving.jodconverter.office.ManagedOfficeProcess doEnsureProcessExited
INFO: process exited with code 0
Aug 17, 2015 1:52:36 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager stop
INFO: stopped
2015-08-17 13:52:36,786 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
2015-08-17 13:52:36,798 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 13:52:36,798 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 13:52:36,799 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
2015-08-17 13:52:36,799 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
2015-08-17 13:52:36,799 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 13:52:36,800 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 13:52:36,800 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 13:52:36,800 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 13:52:36,802 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 13:52:36,802 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 13:52:36,802 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'email' subsystem, ID: [email, outbound]
2015-08-17 13:52:36,803 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'email' subsystem, ID: [email, outbound]
2015-08-17 13:52:36,803 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'imap' subsystem, ID: [imap, default]
2015-08-17 13:52:36,804 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'imap' subsystem, ID: [imap, default]
2015-08-17 13:52:37,258 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 13:52:37,729 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 13:52:37,793 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
2015-08-17 13:52:37,794 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
2015-08-17 13:52:37,795 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 13:52:37,795 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 13:52:37,796 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Transformers' subsystem, ID: [Transformers, default]
2015-08-17 13:52:37,796 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Transformers' subsystem, ID: [Transformers, default]
2015-08-17 13:52:37,796 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
2015-08-17 13:52:37,798 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 13:52:37,799 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 13:52:37,799 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
2015-08-17 13:52:37,800 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 13:52:37,800 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 13:52:37,803 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 17, 2015 1:52:37 PM org.apache.catalina.core.ApplicationContext log
INFO: Shutting down log4j
Aug 17, 2015 1:52:37 PM org.apache.catalina.core.ApplicationContext log
INFO: Destroying Spring FrameworkServlet 'Spring Surf Dispatcher Servlet'
Aug 17, 2015 1:52:37 PM org.apache.catalina.core.ApplicationContext log
INFO: org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: destroy called
Aug 17, 2015 1:52:37 PM org.apache.catalina.core.ApplicationContext log
INFO: Closing Spring root WebApplicationContext
Aug 17, 2015 1:52:39 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 1:52:39 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 1:52:39 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 1:52:39 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 1:52:39 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 1:52:39 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 1:53:53 PM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: /Users/vthakur/Library/Java/Extensions:/Library/Java/Extensions:/Network/Library/Java/Extensions:/System/Library/Java/Extensions:/usr/lib/java:.
Aug 17, 2015 1:53:53 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 1:53:53 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 1:53:53 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 1:53:53 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 472 ms
Aug 17, 2015 1:53:53 PM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Aug 17, 2015 1:53:53 PM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.59
Aug 17, 2015 1:53:53 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor /Applications/alfresco-5.0.1.5/tomcat/conf/Catalina/localhost/solr4.xml
Aug 17, 2015 1:53:53 PM org.apache.catalina.startup.SetContextPropertiesRule begin
WARNING: [SetContextPropertiesRule]{Context} Setting property 'debug' to '0' did not find a matching property.
Aug 17, 2015 1:53:56 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
2015-08-17 13:53:58,298 INFO [solr.component.AsyncBuildSuggestComponent] [coreLoadExecutor-5-thread-2] Initializing SuggestComponent
2015-08-17 13:53:58,299 INFO [solr.component.AsyncBuildSuggestComponent] [coreLoadExecutor-5-thread-1] Initializing SuggestComponent
2015-08-17 13:53:58,378 INFO [solr.component.AsyncBuildSuggestComponent] [Suggestor-alfresco-1] Loading suggester index for: shingleBasedSuggestions
Aug 17, 2015 1:53:58 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deployment of configuration descriptor /Applications/alfresco-5.0.1.5/tomcat/conf/Catalina/localhost/solr4.xml has finished in 5,104 ms
Aug 17, 2015 1:53:58 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/_vti_bin.war
Aug 17, 2015 1:53:58 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/_vti_bin.war has finished in 76 ms
Aug 17, 2015 1:53:58 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/alfresco.war
2015-08-17 13:53:58,694 INFO [solr.component.AsyncBuildSuggestComponent] [Suggestor-alfresco-1] Loaded suggester shingleBasedSuggestions, took 316 ms
Aug 17, 2015 1:54:03 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core_rt is already defined
Aug 17, 2015 1:54:03 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core is already defined
Aug 17, 2015 1:54:03 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/core is already defined
Aug 17, 2015 1:54:03 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/fmt_rt is already defined
Aug 17, 2015 1:54:03 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/fmt is already defined
Aug 17, 2015 1:54:03 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/fmt is already defined
Aug 17, 2015 1:54:03 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/functions is already defined
Aug 17, 2015 1:54:03 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/standard/permittedTaglibs is already defined
Aug 17, 2015 1:54:03 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/standard/scriptfree is already defined
Aug 17, 2015 1:54:03 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/sql_rt is already defined
Aug 17, 2015 1:54:03 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/sql is already defined
Aug 17, 2015 1:54:03 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/sql is already defined
Aug 17, 2015 1:54:03 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/xml_rt is already defined
Aug 17, 2015 1:54:03 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/xml is already defined
Aug 17, 2015 1:54:03 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/xml is already defined
Aug 17, 2015 1:54:03 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Aug 17, 2015 1:54:03 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2015-08-17 13:54:05,953 INFO [alfresco.repo.admin] [localhost-startStop-1] Using database URL 'jdbcostgresql://localhost:5432/alfresco' with user 'alfresco'.
2015-08-17 13:54:06,078 INFO [alfresco.repo.admin] [localhost-startStop-1] Connected to database PostgreSQL version 9.3.6
2015-08-17 13:54:10,041 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 13:54:10,061 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
2015-08-17 13:54:10,072 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Ignoring script patch (post-Hibernate): patch.db-V4.2-metadata-query-indexes
2015-08-17 13:54:15,220 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor Repository Template Processor for extension ftl
2015-08-17 13:54:15,222 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor Repository Script Processor for extension js
2015-08-17 13:54:16,937 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 13:54:16,962 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted] complete
2015-08-17 13:54:16,998 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Connecting to database: jdbcostgresql://localhost:5432/alfresco, UserName=alfresco, PostgreSQL Native Driver
2015-08-17 13:54:16,998 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Schema managed by database dialect org.hibernate.dialect.PostgreSQLDialect.
2015-08-17 13:54:18,195 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] No changes were made to the schema.
2015-08-17 13:54:18,792 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 13:54:18,894 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
2015-08-17 13:54:19,416 INFO [repo.sync.SyncAdminServiceImpl] [localhost-startStop-1] There is no key for cloud sync, cloud sync turned off
2015-08-17 13:54:19,424 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 13:54:19,509 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
2015-08-17 13:54:19,509 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 13:54:19,560 WARN [alfresco.util.AbstractTriggerBean] [localhost-startStop-1] Job openOfficeConnectionTesterTrigger is not enabled
2015-08-17 13:54:19,671 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
2015-08-17 13:54:19,671 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
Aug 17, 2015 1:54:19 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager <init>
INFO: ProcessManager implementation is PureJavaProcessManager
Aug 17, 2015 1:54:19 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Using GNU based LibreOffice command on Mac: [/Applications/alfresco-5.0.1.5/libreoffice.app/Contents/MacOS/soffice, –accept=socket,host=127.0.0.1,port=8100;urp;, -env:UserInstallation=file:///Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100, –headless, –nocrashreport, –nodefault, –nofirststartwizard, –nolockcheck, –nologo, –norestore]
Aug 17, 2015 1:54:19 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Using GNU based LibreOffice 3.6 command on Mac: [/Applications/alfresco-5.0.1.5/libreoffice.app/Contents/MacOS/soffice, –accept=socket,host=127.0.0.1,port=8100;urp;, -env:UserInstallation=file:///Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100, –headless, –nocrashreport, –nodefault, –nofirststartwizard, –nolockcheck, –nologo, –norestore]
Aug 17, 2015 1:54:19 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Removing $DYLD_LIBRARY_PATH from the environment so that LibreOffice/OpenOffice will start on Mac.
Aug 17, 2015 1:54:19 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: starting process with acceptString 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1' and profileDir '/Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100'
Aug 17, 2015 1:54:19 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: started process
Aug 17, 2015 1:54:21 PM org.artofsolving.jodconverter.office.OfficeConnection connect
INFO: connected: 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1'
2015-08-17 13:54:21,567 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default] complete
2015-08-17 13:54:21,570 WARN [cluster.core.ClusteringBootstrap] [localhost-startStop-1] License does not permit clustering: clustering is disabled.
2015-08-17 13:54:21,576 INFO [repo.admin.ConfigurationChecker] [localhost-startStop-1] The Alfresco root data directory ('dir.root') is: /Applications/alfresco-5.0.1.5/alf_data
2015-08-17 13:54:21,576 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 13:54:21,795 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Search' subsystem, ID: [Search, managed, solr4] complete
2015-08-17 13:54:21,800 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Checking for patches to apply …
2015-08-17 13:54:22,041 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] No patches were required.
2015-08-17 13:54:22,056 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Found 1 module(s).
2015-08-17 13:54:22,080 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Starting module 'org.alfresco.integrations.google.docs' version 3.0.0.
2015-08-17 13:54:22,085 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 13:54:22,419 WARN [org.alfresco.fileserver] [localhost-startStop-1] CIFS server name is longer than 15 characters, truncated to Administrators-
2015-08-17 13:54:22,433 WARN [org.alfresco.fileserver] [localhost-startStop-1] CIFS, Unable to get local domain/workgroup name, using default of WORKGROUP. This may be due to firewall settings or incorrect <broadcast> setting)
2015-08-17 13:54:22,439 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
2015-08-17 13:54:22,439 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'imap' subsystem, ID: [imap, default]
2015-08-17 13:54:22,454 ERROR [org.alfresco.fileserver] [CIFS Server] [SMB] Server error : org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
2015-08-17 13:54:22,458 ERROR [org.alfresco.fileserver] [CIFS Server] Error from JLAN
org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
   at org.alfresco.jlan.smb.server.nio.NIOCifsConnectionsHandler.initializeHandler(NIOCifsConnectionsHandler.java:259)
   at org.alfresco.jlan.smb.server.SMBServer.run(SMBServer.java:479)
   at java.lang.Thread.run(Thread.java:745)
2015-08-17 13:54:22,518 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'imap' subsystem, ID: [imap, default] complete
2015-08-17 13:54:22,518 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'email' subsystem, ID: [email, outbound]
2015-08-17 13:54:22,564 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'email' subsystem, ID: [email, outbound] complete
2015-08-17 13:54:22,564 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'email' subsystem, ID: [email, inbound]
2015-08-17 13:54:22,629 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'email' subsystem, ID: [email, inbound] complete
2015-08-17 13:54:22,629 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 13:54:22,643 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Subscriptions' subsystem, ID: [Subscriptions, default] complete
2015-08-17 13:54:22,665 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 13:54:22,763 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
2015-08-17 13:54:22,801 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco JVM - v1.8.0_31-b13; maximum heap size 8125.500MB
2015-08-17 13:54:22,821 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco license: Mode ENTERPRISE, NO CLUSTER granted to Trial User limited to 30 days expiring Wed Sep 16 00:00:00 IST 2015 (30 days remaining).
2015-08-17 13:54:22,821 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Server Mode :UNKNOWN
2015-08-17 13:54:22,821 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco started (Enterprise). Current version: 5.0.1 (.5 r108957-b87) schema 8,022. Originally installed version: 5.0.1 (.5 r108957-b87) schema 8,022.
2015-08-17 13:54:22,832 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 13:54:22,999 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default] complete
2015-08-17 13:54:22,999 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Replication' subsystem, ID: [Replication, default]
2015-08-17 13:54:23,012 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Replication' subsystem, ID: [Replication, default] complete
2015-08-17 13:54:23,860 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 13:54:23,950 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'googledocs' subsystem, ID: [googledocs, drive] complete
2015-08-17 13:54:26,488 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 13:54:26,489 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 13:54:26,489 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:54:27,902 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 13:54:27,902 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 13:54:27,903 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:54:27,907 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Repository Web Script Container (in 3923.4568ms)
2015-08-17 13:54:27,923 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 13:54:27,925 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 13:54:29,216 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 13:54:29,216 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 13:54:29,216 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:54:29,268 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 13:54:29,268 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 13:54:29,268 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:54:29,321 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 13:54:29,321 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 13:54:29,321 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:54:29,322 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Public Api Web Script Container (in 1359.1241ms)
2015-08-17 13:54:29,351 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 13:54:29,351 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 13:54:29,351 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
Aug 17, 2015 1:54:29 PM com.sun.xml.ws.transport.http.servlet.WSServletContextListener contextInitialized
INFO: WSSERVLET12: JAX-WS context listener initializing
Aug 17, 2015 1:54:31 PM com.sun.xml.ws.transport.http.servlet.WSServletDelegate <init>
INFO: WSSERVLET14: JAX-WS servlet initializing
Aug 17, 2015 1:54:32 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/alfresco.war has finished in 33,538 ms
Aug 17, 2015 1:54:32 PM org.apache.catalina.core.StandardContext setPath
WARNING: A context path must either be an empty string or start with a '/' and do not end with a '/'. The path [/] does not meet these criteria and has been changed to []
Aug 17, 2015 1:54:32 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/ROOT.war
Aug 17, 2015 1:54:32 PM org.apache.catalina.startup.SetContextPropertiesRule begin
WARNING: [SetContextPropertiesRule]{Context} Setting property 'debug' to '100' did not find a matching property.
Aug 17, 2015 1:54:32 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/ROOT.war has finished in 52 ms
Aug 17, 2015 1:54:32 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/share.war
Aug 17, 2015 1:54:33 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Aug 17, 2015 1:54:33 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2015-08-17 13:54:36,958 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 13:54:36,962 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 13:54:36,962 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 13:54:36,963 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 13:54:37,830 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 369 Web Scripts (+0 failed), 383 URLs
2015-08-17 13:54:37,830 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 8 Package Description Documents (+0 failed)
2015-08-17 13:54:37,830 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:54:37,874 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Spring Surf Container Web Script Container (in 910.84937ms)
2015-08-17 13:54:37,894 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 13:54:37,894 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
Aug 17, 2015 1:54:38 PM org.apache.catalina.core.ApplicationContext log
INFO: org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: loaded (conf ok)
Aug 17, 2015 1:54:38 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring FrameworkServlet 'Spring Surf Dispatcher Servlet'
Aug 17, 2015 1:54:38 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/share.war has finished in 6,002 ms
Aug 17, 2015 1:54:38 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/host-manager
Aug 17, 2015 1:54:38 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/host-manager has finished in 41 ms
Aug 17, 2015 1:54:38 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/manager
Aug 17, 2015 1:54:38 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/manager has finished in 33 ms
Aug 17, 2015 1:54:38 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 1:54:38 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 1:54:38 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 1:54:38 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 44893 ms
2015-08-17 13:54:52,323 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-3] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 13:54:52,324 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-3] Registered 1 Package Description Documents (+0 failed)
2015-08-17 13:54:52,324 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-3] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:54:52,326 INFO [extensions.webscripts.AbstractRuntimeContainer] [http-bio-8080-exec-3] Initialised Repository Web Script Container (in 1404.8228ms)
2015-08-17 13:54:53,563 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 13:54:53,563 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 13:54:53,563 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
Aug 17, 2015 1:57:31 PM org.apache.catalina.core.StandardServer await
INFO: A valid shutdown command was received via the shutdown port. Stopping the Server instance.
Aug 17, 2015 1:57:31 PM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 1:57:31 PM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 1:57:31 PM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 1:57:31 PM org.apache.catalina.core.StandardService stopInternal
INFO: Stopping service Catalina
Aug 17, 2015 1:57:31 PM com.sun.xml.ws.transport.http.servlet.WSServletDelegate destroy
INFO: WSSERVLET15: JAX-WS servlet destroyed
Aug 17, 2015 1:57:31 PM com.sun.xml.ws.transport.http.servlet.WSServletContextListener contextDestroyed
INFO: WSSERVLET13: JAX-WS context listener destroyed
Aug 17, 2015 1:57:31 PM org.apache.catalina.core.ApplicationContext log
INFO: Closing Spring root WebApplicationContext
2015-08-17 13:57:31,688 INFO [cluster.core.ClusteringBootstrap] [localhost-startStop-2] Clustering has shutdown.
2015-08-17 13:57:31,713 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 13:57:31,714 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 13:57:31,721 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Replication' subsystem, ID: [Replication, default]
2015-08-17 13:57:31,722 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Replication' subsystem, ID: [Replication, default]
2015-08-17 13:57:31,749 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 13:57:31,750 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 13:57:31,751 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'email' subsystem, ID: [email, inbound]
2015-08-17 13:57:31,751 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'email' subsystem, ID: [email, inbound]
2015-08-17 13:57:31,752 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 13:57:31,756 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 13:57:31,766 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
Aug 17, 2015 1:57:31 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager stop
INFO: stopping
Aug 17, 2015 1:57:31 PM org.artofsolving.jodconverter.office.OfficeConnection$1 disposing
INFO: disconnected: 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1'
Aug 17, 2015 1:57:32 PM org.artofsolving.jodconverter.office.ManagedOfficeProcess doEnsureProcessExited
INFO: process exited with code 0
Aug 17, 2015 1:57:32 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager stop
INFO: stopped
2015-08-17 13:57:32,040 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
2015-08-17 13:57:32,053 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 13:57:32,056 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 13:57:32,057 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
2015-08-17 13:57:32,058 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
2015-08-17 13:57:32,058 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 13:57:32,058 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 13:57:32,058 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 13:57:32,059 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 13:57:32,060 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 13:57:32,061 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 13:57:32,061 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'email' subsystem, ID: [email, outbound]
2015-08-17 13:57:32,061 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'email' subsystem, ID: [email, outbound]
2015-08-17 13:57:32,062 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'imap' subsystem, ID: [imap, default]
2015-08-17 13:57:32,062 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'imap' subsystem, ID: [imap, default]
2015-08-17 13:57:32,550 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 13:57:32,904 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 13:57:32,962 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
2015-08-17 13:57:32,963 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
2015-08-17 13:57:32,964 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 13:57:32,965 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 13:57:32,965 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
2015-08-17 13:57:32,967 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 13:57:32,967 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 13:57:32,968 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
2015-08-17 13:57:32,968 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 13:57:32,969 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 13:57:32,971 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 17, 2015 1:57:32 PM org.apache.catalina.core.ApplicationContext log
INFO: Shutting down log4j
Aug 17, 2015 1:57:32 PM org.apache.catalina.core.ApplicationContext log
INFO: Destroying Spring FrameworkServlet 'Spring Surf Dispatcher Servlet'
Aug 17, 2015 1:57:33 PM org.apache.catalina.core.ApplicationContext log
INFO: org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: destroy called
Aug 17, 2015 1:57:33 PM org.apache.catalina.core.ApplicationContext log
INFO: Closing Spring root WebApplicationContext
Aug 17, 2015 1:57:35 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 1:57:35 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 1:57:35 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 1:57:35 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 1:57:35 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 1:57:35 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 1:58:12 PM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: /Users/vthakur/Library/Java/Extensions:/Library/Java/Extensions:/Network/Library/Java/Extensions:/System/Library/Java/Extensions:/usr/lib/java:.
Aug 17, 2015 1:58:12 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 1:58:12 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 1:58:12 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 1:58:12 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 466 ms
Aug 17, 2015 1:58:12 PM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Aug 17, 2015 1:58:12 PM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.59
Aug 17, 2015 1:58:12 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor /Applications/alfresco-5.0.1.5/tomcat/conf/Catalina/localhost/solr4.xml
Aug 17, 2015 1:58:12 PM org.apache.catalina.startup.SetContextPropertiesRule begin
WARNING: [SetContextPropertiesRule]{Context} Setting property 'debug' to '0' did not find a matching property.
Aug 17, 2015 1:58:15 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
2015-08-17 13:58:17,650 INFO [solr.component.AsyncBuildSuggestComponent] [coreLoadExecutor-5-thread-1] Initializing SuggestComponent
2015-08-17 13:58:17,650 INFO [solr.component.AsyncBuildSuggestComponent] [coreLoadExecutor-5-thread-2] Initializing SuggestComponent
2015-08-17 13:58:17,725 INFO [solr.component.AsyncBuildSuggestComponent] [Suggestor-alfresco-1] Loading suggester index for: shingleBasedSuggestions
Aug 17, 2015 1:58:17 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deployment of configuration descriptor /Applications/alfresco-5.0.1.5/tomcat/conf/Catalina/localhost/solr4.xml has finished in 5,139 ms
Aug 17, 2015 1:58:17 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/_vti_bin.war
Aug 17, 2015 1:58:17 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/_vti_bin.war has finished in 95 ms
Aug 17, 2015 1:58:17 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/alfresco.war
2015-08-17 13:58:17,978 INFO [solr.component.AsyncBuildSuggestComponent] [Suggestor-alfresco-1] Loaded suggester shingleBasedSuggestions, took 253 ms
Aug 17, 2015 1:58:22 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core_rt is already defined
Aug 17, 2015 1:58:22 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core is already defined
Aug 17, 2015 1:58:22 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/core is already defined
Aug 17, 2015 1:58:22 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/fmt_rt is already defined
Aug 17, 2015 1:58:22 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/fmt is already defined
Aug 17, 2015 1:58:22 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/fmt is already defined
Aug 17, 2015 1:58:22 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/functions is already defined
Aug 17, 2015 1:58:22 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/standard/permittedTaglibs is already defined
Aug 17, 2015 1:58:22 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/standard/scriptfree is already defined
Aug 17, 2015 1:58:22 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/sql_rt is already defined
Aug 17, 2015 1:58:22 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/sql is already defined
Aug 17, 2015 1:58:22 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/sql is already defined
Aug 17, 2015 1:58:22 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/xml_rt is already defined
Aug 17, 2015 1:58:22 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/xml is already defined
Aug 17, 2015 1:58:22 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/xml is already defined
Aug 17, 2015 1:58:22 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Aug 17, 2015 1:58:22 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2015-08-17 13:58:25,403 INFO [alfresco.repo.admin] [localhost-startStop-1] Using database URL 'jdbcostgresql://localhost:5432/alfresco' with user 'alfresco'.
2015-08-17 13:58:25,535 INFO [alfresco.repo.admin] [localhost-startStop-1] Connected to database PostgreSQL version 9.3.6
2015-08-17 13:58:29,719 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 13:58:29,740 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
2015-08-17 13:58:29,752 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Ignoring script patch (post-Hibernate): patch.db-V4.2-metadata-query-indexes
2015-08-17 13:58:35,025 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor Repository Template Processor for extension ftl
2015-08-17 13:58:35,026 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor Repository Script Processor for extension js
2015-08-17 13:58:36,538 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 13:58:36,559 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted] complete
2015-08-17 13:58:36,592 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Connecting to database: jdbcostgresql://localhost:5432/alfresco, UserName=alfresco, PostgreSQL Native Driver
2015-08-17 13:58:36,592 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Schema managed by database dialect org.hibernate.dialect.PostgreSQLDialect.
2015-08-17 13:58:37,849 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] No changes were made to the schema.
2015-08-17 13:58:38,450 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 13:58:38,554 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
2015-08-17 13:58:39,049 INFO [repo.sync.SyncAdminServiceImpl] [localhost-startStop-1] There is no key for cloud sync, cloud sync turned off
2015-08-17 13:58:39,058 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 13:58:39,139 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
2015-08-17 13:58:39,139 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 13:58:39,191 WARN [alfresco.util.AbstractTriggerBean] [localhost-startStop-1] Job openOfficeConnectionTesterTrigger is not enabled
2015-08-17 13:58:39,312 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
2015-08-17 13:58:39,312 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
Aug 17, 2015 1:58:39 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager <init>
INFO: ProcessManager implementation is PureJavaProcessManager
Aug 17, 2015 1:58:39 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Using GNU based LibreOffice command on Mac: [/Applications/alfresco-5.0.1.5/libreoffice.app/Contents/MacOS/soffice, –accept=socket,host=127.0.0.1,port=8100;urp;, -env:UserInstallation=file:///Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100, –headless, –nocrashreport, –nodefault, –nofirststartwizard, –nolockcheck, –nologo, –norestore]
Aug 17, 2015 1:58:39 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Using GNU based LibreOffice 3.6 command on Mac: [/Applications/alfresco-5.0.1.5/libreoffice.app/Contents/MacOS/soffice, –accept=socket,host=127.0.0.1,port=8100;urp;, -env:UserInstallation=file:///Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100, –headless, –nocrashreport, –nodefault, –nofirststartwizard, –nolockcheck, –nologo, –norestore]
Aug 17, 2015 1:58:39 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Removing $DYLD_LIBRARY_PATH from the environment so that LibreOffice/OpenOffice will start on Mac.
Aug 17, 2015 1:58:39 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: starting process with acceptString 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1' and profileDir '/Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100'
Aug 17, 2015 1:58:39 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: started process
Aug 17, 2015 1:58:40 PM org.artofsolving.jodconverter.office.OfficeConnection connect
INFO: connected: 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1'
2015-08-17 13:58:40,952 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default] complete
2015-08-17 13:58:40,955 WARN [cluster.core.ClusteringBootstrap] [localhost-startStop-1] License does not permit clustering: clustering is disabled.
2015-08-17 13:58:40,960 INFO [repo.admin.ConfigurationChecker] [localhost-startStop-1] The Alfresco root data directory ('dir.root') is: /Applications/alfresco-5.0.1.5/alf_data
2015-08-17 13:58:40,960 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 13:58:41,181 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Search' subsystem, ID: [Search, managed, solr4] complete
2015-08-17 13:58:41,186 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Checking for patches to apply …
2015-08-17 13:58:41,435 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] No patches were required.
2015-08-17 13:58:41,454 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Found 1 module(s).
2015-08-17 13:58:41,477 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Starting module 'org.alfresco.integrations.google.docs' version 3.0.0.
2015-08-17 13:58:41,481 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 13:58:41,784 WARN [org.alfresco.fileserver] [localhost-startStop-1] CIFS server name is longer than 15 characters, truncated to Administrators-
2015-08-17 13:58:41,795 WARN [org.alfresco.fileserver] [localhost-startStop-1] CIFS, Unable to get local domain/workgroup name, using default of WORKGROUP. This may be due to firewall settings or incorrect <broadcast> setting)
2015-08-17 13:58:41,801 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
2015-08-17 13:58:41,802 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'imap' subsystem, ID: [imap, default]
2015-08-17 13:58:41,819 ERROR [org.alfresco.fileserver] [CIFS Server] [SMB] Server error : org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
2015-08-17 13:58:41,824 ERROR [org.alfresco.fileserver] [CIFS Server] Error from JLAN
org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
   at org.alfresco.jlan.smb.server.nio.NIOCifsConnectionsHandler.initializeHandler(NIOCifsConnectionsHandler.java:259)
   at org.alfresco.jlan.smb.server.SMBServer.run(SMBServer.java:479)
   at java.lang.Thread.run(Thread.java:745)
2015-08-17 13:58:41,883 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'imap' subsystem, ID: [imap, default] complete
2015-08-17 13:58:41,883 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'email' subsystem, ID: [email, outbound]
2015-08-17 13:58:41,931 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'email' subsystem, ID: [email, outbound] complete
2015-08-17 13:58:41,931 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'email' subsystem, ID: [email, inbound]
2015-08-17 13:58:42,002 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'email' subsystem, ID: [email, inbound] complete
2015-08-17 13:58:42,002 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 13:58:42,021 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Subscriptions' subsystem, ID: [Subscriptions, default] complete
2015-08-17 13:58:42,041 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 13:58:42,150 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
2015-08-17 13:58:42,184 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco JVM - v1.8.0_31-b13; maximum heap size 8125.500MB
2015-08-17 13:58:42,202 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco license: Mode ENTERPRISE, NO CLUSTER granted to Trial User limited to 30 days expiring Wed Sep 16 00:00:00 IST 2015 (30 days remaining).
2015-08-17 13:58:42,202 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Server Mode :UNKNOWN
2015-08-17 13:58:42,203 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco started (Enterprise). Current version: 5.0.1 (.5 r108957-b87) schema 8,022. Originally installed version: 5.0.1 (.5 r108957-b87) schema 8,022.
2015-08-17 13:58:42,212 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 13:58:42,388 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default] complete
2015-08-17 13:58:42,388 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Replication' subsystem, ID: [Replication, default]
2015-08-17 13:58:42,399 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Replication' subsystem, ID: [Replication, default] complete
2015-08-17 13:58:43,229 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 13:58:43,300 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'googledocs' subsystem, ID: [googledocs, drive] complete
2015-08-17 13:58:45,724 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 13:58:45,725 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 13:58:45,725 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:58:47,190 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 13:58:47,190 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 13:58:47,190 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:58:47,192 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Repository Web Script Container (in 3860.3384ms)
2015-08-17 13:58:47,207 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 13:58:47,209 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 13:58:48,463 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 13:58:48,463 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 13:58:48,463 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:58:48,514 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 13:58:48,514 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 13:58:48,515 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:58:48,603 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 13:58:48,603 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 13:58:48,603 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:58:48,604 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Public Api Web Script Container (in 1355.6085ms)
2015-08-17 13:58:48,634 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 13:58:48,634 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 13:58:48,634 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
Aug 17, 2015 1:58:48 PM com.sun.xml.ws.transport.http.servlet.WSServletContextListener contextInitialized
INFO: WSSERVLET12: JAX-WS context listener initializing
Aug 17, 2015 1:58:50 PM com.sun.xml.ws.transport.http.servlet.WSServletDelegate <init>
INFO: WSSERVLET14: JAX-WS servlet initializing
Aug 17, 2015 1:58:51 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/alfresco.war has finished in 33,346 ms
Aug 17, 2015 1:58:51 PM org.apache.catalina.core.StandardContext setPath
WARNING: A context path must either be an empty string or start with a '/' and do not end with a '/'. The path [/] does not meet these criteria and has been changed to []
Aug 17, 2015 1:58:51 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/ROOT.war
Aug 17, 2015 1:58:51 PM org.apache.catalina.startup.SetContextPropertiesRule begin
WARNING: [SetContextPropertiesRule]{Context} Setting property 'debug' to '100' did not find a matching property.
Aug 17, 2015 1:58:51 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/ROOT.war has finished in 55 ms
Aug 17, 2015 1:58:51 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/share.war
Aug 17, 2015 1:58:52 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Aug 17, 2015 1:58:52 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2015-08-17 13:58:56,341 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 13:58:56,347 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 13:58:56,348 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 13:58:56,349 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 13:58:57,254 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 369 Web Scripts (+0 failed), 383 URLs
2015-08-17 13:58:57,254 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 8 Package Description Documents (+0 failed)
2015-08-17 13:58:57,254 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:58:57,323 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Spring Surf Container Web Script Container (in 973.7298ms)
2015-08-17 13:58:57,342 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 13:58:57,343 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
Aug 17, 2015 1:58:57 PM org.apache.catalina.core.ApplicationContext log
INFO: org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: loaded (conf ok)
Aug 17, 2015 1:58:57 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring FrameworkServlet 'Spring Surf Dispatcher Servlet'
Aug 17, 2015 1:58:57 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/share.war has finished in 6,292 ms
Aug 17, 2015 1:58:57 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/host-manager
Aug 17, 2015 1:58:57 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/host-manager has finished in 43 ms
Aug 17, 2015 1:58:57 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/manager
Aug 17, 2015 1:58:57 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/manager has finished in 37 ms
Aug 17, 2015 1:58:57 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 1:58:57 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 1:58:57 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 1:58:57 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 45054 ms
2015-08-17 13:58:59,285 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 13:58:59,285 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 13:58:59,286 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:58:59,288 INFO [extensions.webscripts.AbstractRuntimeContainer] [http-bio-8080-exec-1] Initialised Repository Web Script Container (in 1349.1797ms)
2015-08-17 13:59:00,598 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 13:59:00,598 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 13:59:00,598 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:59:37,390 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-8] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 13:59:37,391 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-8] Registered 1 Package Description Documents (+0 failed)
2015-08-17 13:59:37,391 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-8] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 13:59:37,392 INFO [extensions.webscripts.AbstractRuntimeContainer] [http-bio-8080-exec-8] Initialised Repository Web Script Container (in 1187.6396ms)
2015-08-17 13:59:38,494 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 13:59:38,494 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 13:59:38,494 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
Aug 17, 2015 2:03:23 PM org.apache.catalina.core.StandardServer await
INFO: A valid shutdown command was received via the shutdown port. Stopping the Server instance.
Aug 17, 2015 2:03:23 PM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 2:03:23 PM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 2:03:23 PM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 2:03:23 PM org.apache.catalina.core.StandardService stopInternal
INFO: Stopping service Catalina
Aug 17, 2015 2:03:23 PM com.sun.xml.ws.transport.http.servlet.WSServletDelegate destroy
INFO: WSSERVLET15: JAX-WS servlet destroyed
Aug 17, 2015 2:03:23 PM com.sun.xml.ws.transport.http.servlet.WSServletContextListener contextDestroyed
INFO: WSSERVLET13: JAX-WS context listener destroyed
Aug 17, 2015 2:03:23 PM org.apache.catalina.core.ApplicationContext log
INFO: Closing Spring root WebApplicationContext
2015-08-17 14:03:23,509 INFO [cluster.core.ClusteringBootstrap] [localhost-startStop-2] Clustering has shutdown.
2015-08-17 14:03:23,529 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 14:03:23,533 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 14:03:23,542 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Replication' subsystem, ID: [Replication, default]
2015-08-17 14:03:23,543 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Replication' subsystem, ID: [Replication, default]
2015-08-17 14:03:23,557 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 14:03:23,559 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 14:03:23,559 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'email' subsystem, ID: [email, inbound]
2015-08-17 14:03:23,560 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'email' subsystem, ID: [email, inbound]
2015-08-17 14:03:23,560 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 14:03:23,564 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 14:03:23,565 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
Aug 17, 2015 2:03:23 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager stop
INFO: stopping
Aug 17, 2015 2:03:23 PM org.artofsolving.jodconverter.office.OfficeConnection$1 disposing
INFO: disconnected: 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1'
Aug 17, 2015 2:03:23 PM org.artofsolving.jodconverter.office.ManagedOfficeProcess doEnsureProcessExited
INFO: process exited with code 0
Aug 17, 2015 2:03:23 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager stop
INFO: stopped
2015-08-17 14:03:23,838 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
2015-08-17 14:03:23,864 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 14:03:23,865 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 14:03:23,865 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
2015-08-17 14:03:23,866 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
2015-08-17 14:03:23,866 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 14:03:23,867 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 14:03:23,867 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 14:03:23,867 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 14:03:23,870 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 14:03:23,871 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 14:03:23,871 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'email' subsystem, ID: [email, outbound]
2015-08-17 14:03:23,871 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'email' subsystem, ID: [email, outbound]
2015-08-17 14:03:23,872 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'imap' subsystem, ID: [imap, default]
2015-08-17 14:03:23,874 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'imap' subsystem, ID: [imap, default]
2015-08-17 14:03:24,402 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 14:03:24,901 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 14:03:24,959 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
2015-08-17 14:03:24,960 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
2015-08-17 14:03:24,961 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 14:03:24,962 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 14:03:24,962 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
2015-08-17 14:03:24,964 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 14:03:24,964 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 14:03:24,964 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
2015-08-17 14:03:24,965 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 14:03:24,966 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 14:03:24,968 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 17, 2015 2:03:24 PM org.apache.catalina.core.ApplicationContext log
INFO: Shutting down log4j
Aug 17, 2015 2:03:24 PM org.apache.catalina.core.ApplicationContext log
INFO: Destroying Spring FrameworkServlet 'Spring Surf Dispatcher Servlet'
Aug 17, 2015 2:03:25 PM org.apache.catalina.core.ApplicationContext log
INFO: org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: destroy called
Aug 17, 2015 2:03:25 PM org.apache.catalina.core.ApplicationContext log
INFO: Closing Spring root WebApplicationContext
Aug 17, 2015 2:03:56 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 2:03:56 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 2:03:56 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 2:03:56 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 2:03:56 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 2:03:56 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 2:04:08 PM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: /Users/vthakur/Library/Java/Extensions:/Library/Java/Extensions:/Network/Library/Java/Extensions:/System/Library/Java/Extensions:/usr/lib/java:.
Aug 17, 2015 2:04:08 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 2:04:08 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 2:04:08 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 2:04:08 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 476 ms
Aug 17, 2015 2:04:08 PM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Aug 17, 2015 2:04:08 PM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.59
Aug 17, 2015 2:04:08 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor /Applications/alfresco-5.0.1.5/tomcat/conf/Catalina/localhost/solr4.xml
Aug 17, 2015 2:04:08 PM org.apache.catalina.startup.SetContextPropertiesRule begin
WARNING: [SetContextPropertiesRule]{Context} Setting property 'debug' to '0' did not find a matching property.
Aug 17, 2015 2:04:11 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
2015-08-17 14:04:13,320 INFO [solr.component.AsyncBuildSuggestComponent] [coreLoadExecutor-5-thread-2] Initializing SuggestComponent
2015-08-17 14:04:13,320 INFO [solr.component.AsyncBuildSuggestComponent] [coreLoadExecutor-5-thread-1] Initializing SuggestComponent
2015-08-17 14:04:13,396 INFO [solr.component.AsyncBuildSuggestComponent] [Suggestor-alfresco-1] Loading suggester index for: shingleBasedSuggestions
Aug 17, 2015 2:04:13 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deployment of configuration descriptor /Applications/alfresco-5.0.1.5/tomcat/conf/Catalina/localhost/solr4.xml has finished in 4,983 ms
Aug 17, 2015 2:04:13 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/_vti_bin.war
Aug 17, 2015 2:04:13 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/_vti_bin.war has finished in 85 ms
Aug 17, 2015 2:04:13 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/alfresco.war
2015-08-17 14:04:13,658 INFO [solr.component.AsyncBuildSuggestComponent] [Suggestor-alfresco-1] Loaded suggester shingleBasedSuggestions, took 261 ms
Aug 17, 2015 2:04:18 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core_rt is already defined
Aug 17, 2015 2:04:18 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core is already defined
Aug 17, 2015 2:04:18 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/core is already defined
Aug 17, 2015 2:04:18 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/fmt_rt is already defined
Aug 17, 2015 2:04:18 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/fmt is already defined
Aug 17, 2015 2:04:18 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/fmt is already defined
Aug 17, 2015 2:04:18 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/functions is already defined
Aug 17, 2015 2:04:18 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/standard/permittedTaglibs is already defined
Aug 17, 2015 2:04:18 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/standard/scriptfree is already defined
Aug 17, 2015 2:04:18 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/sql_rt is already defined
Aug 17, 2015 2:04:18 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/sql is already defined
Aug 17, 2015 2:04:18 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/sql is already defined
Aug 17, 2015 2:04:18 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/xml_rt is already defined
Aug 17, 2015 2:04:18 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/xml is already defined
Aug 17, 2015 2:04:18 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/xml is already defined
Aug 17, 2015 2:04:18 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Aug 17, 2015 2:04:18 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2015-08-17 14:04:20,595 INFO [alfresco.repo.admin] [localhost-startStop-1] Using database URL 'jdbcostgresql://localhost:5432/alfresco' with user 'alfresco'.
2015-08-17 14:04:20,749 INFO [alfresco.repo.admin] [localhost-startStop-1] Connected to database PostgreSQL version 9.3.6
2015-08-17 14:04:24,625 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 14:04:24,646 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
2015-08-17 14:04:24,656 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Ignoring script patch (post-Hibernate): patch.db-V4.2-metadata-query-indexes
2015-08-17 14:04:29,601 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor Repository Template Processor for extension ftl
2015-08-17 14:04:29,603 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor Repository Script Processor for extension js
2015-08-17 14:04:31,036 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 14:04:31,063 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted] complete
2015-08-17 14:04:31,095 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Connecting to database: jdbcostgresql://localhost:5432/alfresco, UserName=alfresco, PostgreSQL Native Driver
2015-08-17 14:04:31,095 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Schema managed by database dialect org.hibernate.dialect.PostgreSQLDialect.
2015-08-17 14:04:32,301 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] No changes were made to the schema.
2015-08-17 14:04:32,905 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 14:04:33,006 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
2015-08-17 14:04:33,505 INFO [repo.sync.SyncAdminServiceImpl] [localhost-startStop-1] There is no key for cloud sync, cloud sync turned off
2015-08-17 14:04:33,513 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 14:04:33,589 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
2015-08-17 14:04:33,589 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 14:04:33,635 WARN [alfresco.util.AbstractTriggerBean] [localhost-startStop-1] Job openOfficeConnectionTesterTrigger is not enabled
2015-08-17 14:04:33,749 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
2015-08-17 14:04:33,749 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
Aug 17, 2015 2:04:33 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager <init>
INFO: ProcessManager implementation is PureJavaProcessManager
Aug 17, 2015 2:04:33 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Using GNU based LibreOffice command on Mac: [/Applications/alfresco-5.0.1.5/libreoffice.app/Contents/MacOS/soffice, –accept=socket,host=127.0.0.1,port=8100;urp;, -env:UserInstallation=file:///Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100, –headless, –nocrashreport, –nodefault, –nofirststartwizard, –nolockcheck, –nologo, –norestore]
Aug 17, 2015 2:04:33 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Using GNU based LibreOffice 3.6 command on Mac: [/Applications/alfresco-5.0.1.5/libreoffice.app/Contents/MacOS/soffice, –accept=socket,host=127.0.0.1,port=8100;urp;, -env:UserInstallation=file:///Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100, –headless, –nocrashreport, –nodefault, –nofirststartwizard, –nolockcheck, –nologo, –norestore]
Aug 17, 2015 2:04:33 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Removing $DYLD_LIBRARY_PATH from the environment so that LibreOffice/OpenOffice will start on Mac.
Aug 17, 2015 2:04:33 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: starting process with acceptString 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1' and profileDir '/Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100'
Aug 17, 2015 2:04:33 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: started process
Aug 17, 2015 2:04:35 PM org.artofsolving.jodconverter.office.OfficeConnection connect
INFO: connected: 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1'
2015-08-17 14:04:35,407 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default] complete
2015-08-17 14:04:35,409 WARN [cluster.core.ClusteringBootstrap] [localhost-startStop-1] License does not permit clustering: clustering is disabled.
2015-08-17 14:04:35,414 INFO [repo.admin.ConfigurationChecker] [localhost-startStop-1] The Alfresco root data directory ('dir.root') is: /Applications/alfresco-5.0.1.5/alf_data
2015-08-17 14:04:35,414 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 14:04:35,603 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Search' subsystem, ID: [Search, managed, solr4] complete
2015-08-17 14:04:35,612 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Checking for patches to apply …
2015-08-17 14:04:35,814 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] No patches were required.
2015-08-17 14:04:35,828 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Found 1 module(s).
2015-08-17 14:04:35,846 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Starting module 'org.alfresco.integrations.google.docs' version 3.0.0.
2015-08-17 14:04:35,850 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 14:04:36,116 WARN [org.alfresco.fileserver] [localhost-startStop-1] CIFS server name is longer than 15 characters, truncated to Administrators-
2015-08-17 14:04:36,126 WARN [org.alfresco.fileserver] [localhost-startStop-1] CIFS, Unable to get local domain/workgroup name, using default of WORKGROUP. This may be due to firewall settings or incorrect <broadcast> setting)
2015-08-17 14:04:36,132 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
2015-08-17 14:04:36,132 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'imap' subsystem, ID: [imap, default]
2015-08-17 14:04:36,147 ERROR [org.alfresco.fileserver] [CIFS Server] [SMB] Server error : org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
2015-08-17 14:04:36,150 ERROR [org.alfresco.fileserver] [CIFS Server] Error from JLAN
org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
   at org.alfresco.jlan.smb.server.nio.NIOCifsConnectionsHandler.initializeHandler(NIOCifsConnectionsHandler.java:259)
   at org.alfresco.jlan.smb.server.SMBServer.run(SMBServer.java:479)
   at java.lang.Thread.run(Thread.java:745)
2015-08-17 14:04:36,199 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'imap' subsystem, ID: [imap, default] complete
2015-08-17 14:04:36,199 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'email' subsystem, ID: [email, outbound]
2015-08-17 14:04:36,244 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'email' subsystem, ID: [email, outbound] complete
2015-08-17 14:04:36,244 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'email' subsystem, ID: [email, inbound]
2015-08-17 14:04:36,286 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'email' subsystem, ID: [email, inbound] complete
2015-08-17 14:04:36,286 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 14:04:36,317 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Subscriptions' subsystem, ID: [Subscriptions, default] complete
2015-08-17 14:04:36,338 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 14:04:36,429 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
2015-08-17 14:04:36,470 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco JVM - v1.8.0_31-b13; maximum heap size 8125.500MB
2015-08-17 14:04:36,486 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco license: Mode ENTERPRISE, NO CLUSTER granted to Trial User limited to 30 days expiring Wed Sep 16 00:00:00 IST 2015 (30 days remaining).
2015-08-17 14:04:36,487 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Server Mode :UNKNOWN
2015-08-17 14:04:36,487 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco started (Enterprise). Current version: 5.0.1 (.5 r108957-b87) schema 8,022. Originally installed version: 5.0.1 (.5 r108957-b87) schema 8,022.
2015-08-17 14:04:36,496 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 14:04:36,670 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default] complete
2015-08-17 14:04:36,670 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Replication' subsystem, ID: [Replication, default]
2015-08-17 14:04:36,684 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Replication' subsystem, ID: [Replication, default] complete
2015-08-17 14:04:37,461 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 14:04:37,528 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'googledocs' subsystem, ID: [googledocs, drive] complete
2015-08-17 14:04:39,698 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 14:04:39,699 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 14:04:39,699 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 14:04:40,994 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 14:04:40,994 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 14:04:40,994 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 14:04:40,996 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Repository Web Script Container (in 3440.4438ms)
2015-08-17 14:04:41,008 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 14:04:41,010 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 14:04:42,169 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 14:04:42,169 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 14:04:42,169 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 14:04:42,213 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 14:04:42,213 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 14:04:42,213 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 14:04:42,306 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 14:04:42,306 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 14:04:42,306 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 14:04:42,307 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Public Api Web Script Container (in 1262.0895ms)
2015-08-17 14:04:42,336 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 14:04:42,336 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 14:04:42,336 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
Aug 17, 2015 2:04:42 PM com.sun.xml.ws.transport.http.servlet.WSServletContextListener contextInitialized
INFO: WSSERVLET12: JAX-WS context listener initializing
Aug 17, 2015 2:04:44 PM com.sun.xml.ws.transport.http.servlet.WSServletDelegate <init>
INFO: WSSERVLET14: JAX-WS servlet initializing
Aug 17, 2015 2:04:44 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/alfresco.war has finished in 31,196 ms
Aug 17, 2015 2:04:44 PM org.apache.catalina.core.StandardContext setPath
WARNING: A context path must either be an empty string or start with a '/' and do not end with a '/'. The path [/] does not meet these criteria and has been changed to []
Aug 17, 2015 2:04:44 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/ROOT.war
Aug 17, 2015 2:04:44 PM org.apache.catalina.startup.SetContextPropertiesRule begin
WARNING: [SetContextPropertiesRule]{Context} Setting property 'debug' to '100' did not find a matching property.
Aug 17, 2015 2:04:44 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/ROOT.war has finished in 64 ms
Aug 17, 2015 2:04:44 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/share.war
Aug 17, 2015 2:04:46 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Aug 17, 2015 2:04:46 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2015-08-17 14:04:49,485 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 14:04:49,488 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 14:04:49,489 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 14:04:49,490 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 14:04:50,318 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 369 Web Scripts (+0 failed), 383 URLs
2015-08-17 14:04:50,318 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 8 Package Description Documents (+0 failed)
2015-08-17 14:04:50,318 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 14:04:50,365 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Spring Surf Container Web Script Container (in 875.6115ms)
2015-08-17 14:04:50,383 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 14:04:50,384 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
Aug 17, 2015 2:04:50 PM org.apache.catalina.core.ApplicationContext log
INFO: org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: loaded (conf ok)
Aug 17, 2015 2:04:50 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring FrameworkServlet 'Spring Surf Dispatcher Servlet'
Aug 17, 2015 2:04:50 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/share.war has finished in 5,790 ms
Aug 17, 2015 2:04:50 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/host-manager
Aug 17, 2015 2:04:50 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/host-manager has finished in 40 ms
Aug 17, 2015 2:04:50 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/manager
Aug 17, 2015 2:04:50 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/manager has finished in 38 ms
Aug 17, 2015 2:04:50 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 2:04:50 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 2:04:50 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 2:04:50 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 42244 ms
2015-08-17 14:04:59,172 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 14:04:59,172 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 14:04:59,173 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 14:04:59,179 INFO [extensions.webscripts.AbstractRuntimeContainer] [http-bio-8080-exec-1] Initialised Repository Web Script Container (in 1396.6688ms)
2015-08-17 14:05:00,473 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 14:05:00,473 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 14:05:00,473 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 14:05:07,011 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-4] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 14:05:07,012 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-4] Registered 1 Package Description Documents (+0 failed)
2015-08-17 14:05:07,012 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-4] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 14:05:07,013 INFO [extensions.webscripts.AbstractRuntimeContainer] [http-bio-8080-exec-4] Initialised Repository Web Script Container (in 1197.7144ms)
2015-08-17 14:05:08,117 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 14:05:08,117 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 14:05:08,117 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 14:10:19,088 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-9] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 14:10:19,088 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-9] Registered 1 Package Description Documents (+0 failed)
2015-08-17 14:10:19,089 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-9] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 14:10:19,090 INFO [extensions.webscripts.AbstractRuntimeContainer] [http-bio-8080-exec-9] Initialised Repository Web Script Container (in 1242.6438ms)
2015-08-17 14:10:20,125 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 14:10:20,125 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 14:10:20,125 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
Aug 17, 2015 2:17:55 PM org.apache.catalina.core.StandardServer await
INFO: A valid shutdown command was received via the shutdown port. Stopping the Server instance.
Aug 17, 2015 2:17:55 PM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 2:17:55 PM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 2:17:55 PM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 2:17:55 PM org.apache.catalina.core.StandardService stopInternal
INFO: Stopping service Catalina
Aug 17, 2015 2:17:55 PM com.sun.xml.ws.transport.http.servlet.WSServletDelegate destroy
INFO: WSSERVLET15: JAX-WS servlet destroyed
Aug 17, 2015 2:17:55 PM com.sun.xml.ws.transport.http.servlet.WSServletContextListener contextDestroyed
INFO: WSSERVLET13: JAX-WS context listener destroyed
Aug 17, 2015 2:17:55 PM org.apache.catalina.core.ApplicationContext log
INFO: Closing Spring root WebApplicationContext
2015-08-17 14:17:55,469 INFO [cluster.core.ClusteringBootstrap] [localhost-startStop-2] Clustering has shutdown.
2015-08-17 14:17:55,490 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 14:17:55,493 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 14:17:55,503 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Replication' subsystem, ID: [Replication, default]
2015-08-17 14:17:55,504 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Replication' subsystem, ID: [Replication, default]
2015-08-17 14:17:55,518 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 14:17:55,520 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 14:17:55,520 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'email' subsystem, ID: [email, inbound]
2015-08-17 14:17:55,521 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'email' subsystem, ID: [email, inbound]
2015-08-17 14:17:55,521 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 14:17:55,524 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 14:17:55,525 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
Aug 17, 2015 2:17:55 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager stop
INFO: stopping
Aug 17, 2015 2:17:55 PM org.artofsolving.jodconverter.office.OfficeConnection$1 disposing
INFO: disconnected: 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1'
Aug 17, 2015 2:17:55 PM org.artofsolving.jodconverter.office.ManagedOfficeProcess doEnsureProcessExited
INFO: process exited with code 0
Aug 17, 2015 2:17:55 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager stop
INFO: stopped
2015-08-17 14:17:55,803 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
2015-08-17 14:17:55,817 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 14:17:55,818 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 14:17:55,818 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
2015-08-17 14:17:55,819 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
2015-08-17 14:17:55,819 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 14:17:55,819 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 14:17:55,820 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 14:17:55,820 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 14:17:55,822 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 14:17:55,822 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 14:17:55,822 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'email' subsystem, ID: [email, outbound]
2015-08-17 14:17:55,823 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'email' subsystem, ID: [email, outbound]
2015-08-17 14:17:55,823 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'imap' subsystem, ID: [imap, default]
2015-08-17 14:17:55,824 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'imap' subsystem, ID: [imap, default]
2015-08-17 14:17:56,307 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 14:17:56,522 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 14:17:56,587 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
2015-08-17 14:17:56,588 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
2015-08-17 14:17:56,589 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 14:17:56,590 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 14:17:56,590 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
2015-08-17 14:17:56,592 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 14:17:56,593 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 14:17:56,593 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
2015-08-17 14:17:56,594 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 14:17:56,595 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 14:17:56,597 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 17, 2015 2:17:56 PM org.apache.catalina.core.ApplicationContext log
INFO: Shutting down log4j
Aug 17, 2015 2:17:56 PM org.apache.catalina.core.ApplicationContext log
INFO: Destroying Spring FrameworkServlet 'Spring Surf Dispatcher Servlet'
Aug 17, 2015 2:17:56 PM org.apache.catalina.core.ApplicationContext log
INFO: org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: destroy called
Aug 17, 2015 2:17:56 PM org.apache.catalina.core.ApplicationContext log
INFO: Closing Spring root WebApplicationContext
Aug 17, 2015 2:17:58 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 2:17:58 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 2:17:58 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 2:17:58 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 2:17:58 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 2:17:58 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 2:18:38 PM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: /Users/vthakur/Library/Java/Extensions:/Library/Java/Extensions:/Network/Library/Java/Extensions:/System/Library/Java/Extensions:/usr/lib/java:.
Aug 17, 2015 2:18:39 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 2:18:39 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 2:18:39 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 2:18:39 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 468 ms
Aug 17, 2015 2:18:39 PM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Aug 17, 2015 2:18:39 PM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.59
Aug 17, 2015 2:18:39 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor /Applications/alfresco-5.0.1.5/tomcat/conf/Catalina/localhost/solr4.xml
Aug 17, 2015 2:18:39 PM org.apache.catalina.startup.SetContextPropertiesRule begin
WARNING: [SetContextPropertiesRule]{Context} Setting property 'debug' to '0' did not find a matching property.
Aug 17, 2015 2:18:42 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
2015-08-17 14:18:44,247 INFO [solr.component.AsyncBuildSuggestComponent] [coreLoadExecutor-5-thread-2] Initializing SuggestComponent
2015-08-17 14:18:44,247 INFO [solr.component.AsyncBuildSuggestComponent] [coreLoadExecutor-5-thread-1] Initializing SuggestComponent
2015-08-17 14:18:44,321 INFO [solr.component.AsyncBuildSuggestComponent] [Suggestor-alfresco-1] Loading suggester index for: shingleBasedSuggestions
Aug 17, 2015 2:18:44 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deployment of configuration descriptor /Applications/alfresco-5.0.1.5/tomcat/conf/Catalina/localhost/solr4.xml has finished in 5,080 ms
Aug 17, 2015 2:18:44 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/_vti_bin.war
Aug 17, 2015 2:18:44 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/_vti_bin.war has finished in 89 ms
Aug 17, 2015 2:18:44 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/alfresco.war
2015-08-17 14:18:44,584 INFO [solr.component.AsyncBuildSuggestComponent] [Suggestor-alfresco-1] Loaded suggester shingleBasedSuggestions, took 263 ms
Aug 17, 2015 2:18:49 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core_rt is already defined
Aug 17, 2015 2:18:49 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core is already defined
Aug 17, 2015 2:18:49 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/core is already defined
Aug 17, 2015 2:18:49 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/fmt_rt is already defined
Aug 17, 2015 2:18:49 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/fmt is already defined
Aug 17, 2015 2:18:49 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/fmt is already defined
Aug 17, 2015 2:18:49 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/functions is already defined
Aug 17, 2015 2:18:49 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/standard/permittedTaglibs is already defined
Aug 17, 2015 2:18:49 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/standard/scriptfree is already defined
Aug 17, 2015 2:18:49 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/sql_rt is already defined
Aug 17, 2015 2:18:49 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/sql is already defined
Aug 17, 2015 2:18:49 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/sql is already defined
Aug 17, 2015 2:18:49 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/xml_rt is already defined
Aug 17, 2015 2:18:49 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/xml is already defined
Aug 17, 2015 2:18:49 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/xml is already defined
Aug 17, 2015 2:18:49 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Aug 17, 2015 2:18:49 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2015-08-17 14:18:51,619 INFO [alfresco.repo.admin] [localhost-startStop-1] Using database URL 'jdbcostgresql://localhost:5432/alfresco' with user 'alfresco'.
2015-08-17 14:18:51,744 INFO [alfresco.repo.admin] [localhost-startStop-1] Connected to database PostgreSQL version 9.3.6
2015-08-17 14:18:55,686 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 14:18:55,707 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
2015-08-17 14:18:55,716 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Ignoring script patch (post-Hibernate): patch.db-V4.2-metadata-query-indexes
2015-08-17 14:19:00,984 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor Repository Template Processor for extension ftl
2015-08-17 14:19:01,004 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor Repository Script Processor for extension js
2015-08-17 14:19:02,614 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 14:19:02,639 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted] complete
2015-08-17 14:19:02,683 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Connecting to database: jdbcostgresql://localhost:5432/alfresco, UserName=alfresco, PostgreSQL Native Driver
2015-08-17 14:19:02,683 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Schema managed by database dialect org.hibernate.dialect.PostgreSQLDialect.
2015-08-17 14:19:03,909 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] No changes were made to the schema.
2015-08-17 14:19:04,502 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 14:19:04,610 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
2015-08-17 14:19:05,100 INFO [repo.sync.SyncAdminServiceImpl] [localhost-startStop-1] There is no key for cloud sync, cloud sync turned off
2015-08-17 14:19:05,110 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 14:19:05,191 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
2015-08-17 14:19:05,191 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 14:19:05,242 WARN [alfresco.util.AbstractTriggerBean] [localhost-startStop-1] Job openOfficeConnectionTesterTrigger is not enabled
2015-08-17 14:19:05,353 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
2015-08-17 14:19:05,353 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
Aug 17, 2015 2:19:05 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager <init>
INFO: ProcessManager implementation is PureJavaProcessManager
Aug 17, 2015 2:19:05 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Using GNU based LibreOffice command on Mac: [/Applications/alfresco-5.0.1.5/libreoffice.app/Contents/MacOS/soffice, –accept=socket,host=127.0.0.1,port=8100;urp;, -env:UserInstallation=file:///Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100, –headless, –nocrashreport, –nodefault, –nofirststartwizard, –nolockcheck, –nologo, –norestore]
Aug 17, 2015 2:19:05 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Using GNU based LibreOffice 3.6 command on Mac: [/Applications/alfresco-5.0.1.5/libreoffice.app/Contents/MacOS/soffice, –accept=socket,host=127.0.0.1,port=8100;urp;, -env:UserInstallation=file:///Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100, –headless, –nocrashreport, –nodefault, –nofirststartwizard, –nolockcheck, –nologo, –norestore]
Aug 17, 2015 2:19:05 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Removing $DYLD_LIBRARY_PATH from the environment so that LibreOffice/OpenOffice will start on Mac.
Aug 17, 2015 2:19:05 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: starting process with acceptString 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1' and profileDir '/Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100'
Aug 17, 2015 2:19:05 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: started process
Aug 17, 2015 2:19:06 PM org.artofsolving.jodconverter.office.OfficeConnection connect
INFO: connected: 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1'
2015-08-17 14:19:06,998 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default] complete
2015-08-17 14:19:07,000 WARN [cluster.core.ClusteringBootstrap] [localhost-startStop-1] License does not permit clustering: clustering is disabled.
2015-08-17 14:19:07,005 INFO [repo.admin.ConfigurationChecker] [localhost-startStop-1] The Alfresco root data directory ('dir.root') is: /Applications/alfresco-5.0.1.5/alf_data
2015-08-17 14:19:07,005 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 14:19:07,225 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Search' subsystem, ID: [Search, managed, solr4] complete
2015-08-17 14:19:07,230 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Checking for patches to apply …
2015-08-17 14:19:07,469 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] No patches were required.
2015-08-17 14:19:07,489 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Found 1 module(s).
2015-08-17 14:19:07,508 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Starting module 'org.alfresco.integrations.google.docs' version 3.0.0.
2015-08-17 14:19:07,513 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 14:19:07,815 WARN [org.alfresco.fileserver] [localhost-startStop-1] CIFS server name is longer than 15 characters, truncated to Administrators-
2015-08-17 14:19:07,831 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
2015-08-17 14:19:07,831 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'imap' subsystem, ID: [imap, default]
2015-08-17 14:19:07,845 ERROR [org.alfresco.fileserver] [CIFS Server] [SMB] Server error : org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
2015-08-17 14:19:07,850 ERROR [org.alfresco.fileserver] [CIFS Server] Error from JLAN
org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
   at org.alfresco.jlan.smb.server.nio.NIOCifsConnectionsHandler.initializeHandler(NIOCifsConnectionsHandler.java:259)
   at org.alfresco.jlan.smb.server.SMBServer.run(SMBServer.java:479)
   at java.lang.Thread.run(Thread.java:745)
2015-08-17 14:19:07,911 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'imap' subsystem, ID: [imap, default] complete
2015-08-17 14:19:07,911 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'email' subsystem, ID: [email, outbound]
2015-08-17 14:19:07,961 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'email' subsystem, ID: [email, outbound] complete
2015-08-17 14:19:07,961 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'email' subsystem, ID: [email, inbound]
2015-08-17 14:19:08,005 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'email' subsystem, ID: [email, inbound] complete
2015-08-17 14:19:08,005 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 14:19:08,023 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Subscriptions' subsystem, ID: [Subscriptions, default] complete
2015-08-17 14:19:08,052 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 14:19:08,161 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
2015-08-17 14:19:08,199 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco JVM - v1.8.0_31-b13; maximum heap size 8125.500MB
2015-08-17 14:19:08,219 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco license: Mode ENTERPRISE, NO CLUSTER granted to Trial User limited to 30 days expiring Wed Sep 16 00:00:00 IST 2015 (30 days remaining).
2015-08-17 14:19:08,220 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Server Mode :UNKNOWN
2015-08-17 14:19:08,220 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco started (Enterprise). Current version: 5.0.1 (.5 r108957-b87) schema 8,022. Originally installed version: 5.0.1 (.5 r108957-b87) schema 8,022.
2015-08-17 14:19:08,232 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 14:19:08,404 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default] complete
2015-08-17 14:19:08,404 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Replication' subsystem, ID: [Replication, default]
2015-08-17 14:19:08,420 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Replication' subsystem, ID: [Replication, default] complete
2015-08-17 14:19:09,188 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 14:19:09,254 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'googledocs' subsystem, ID: [googledocs, drive] complete
2015-08-17 14:19:11,414 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 14:19:11,414 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 14:19:11,414 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 14:19:12,766 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 14:19:12,766 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 14:19:12,766 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 14:19:12,769 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Repository Web Script Container (in 3486.2751ms)
2015-08-17 14:19:12,789 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 14:19:12,790 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 14:19:13,974 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 14:19:13,974 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 14:19:13,974 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 14:19:14,017 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 14:19:14,017 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 14:19:14,017 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 14:19:14,104 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 14:19:14,104 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 14:19:14,104 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 14:19:14,106 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Public Api Web Script Container (in 1269.8888ms)
2015-08-17 14:19:14,134 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 14:19:14,134 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 14:19:14,134 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
Aug 17, 2015 2:19:14 PM com.sun.xml.ws.transport.http.servlet.WSServletContextListener contextInitialized
INFO: WSSERVLET12: JAX-WS context listener initializing
Aug 17, 2015 2:19:16 PM com.sun.xml.ws.transport.http.servlet.WSServletDelegate <init>
INFO: WSSERVLET14: JAX-WS servlet initializing
Aug 17, 2015 2:19:16 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/alfresco.war has finished in 32,078 ms
Aug 17, 2015 2:19:16 PM org.apache.catalina.core.StandardContext setPath
WARNING: A context path must either be an empty string or start with a '/' and do not end with a '/'. The path [/] does not meet these criteria and has been changed to []
Aug 17, 2015 2:19:16 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/ROOT.war
Aug 17, 2015 2:19:16 PM org.apache.catalina.startup.SetContextPropertiesRule begin
WARNING: [SetContextPropertiesRule]{Context} Setting property 'debug' to '100' did not find a matching property.
Aug 17, 2015 2:19:16 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/ROOT.war has finished in 44 ms
Aug 17, 2015 2:19:16 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/share.war
Aug 17, 2015 2:19:17 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Aug 17, 2015 2:19:17 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2015-08-17 14:19:21,172 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 14:19:21,175 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 14:19:21,176 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 14:19:21,177 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 14:19:22,025 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 369 Web Scripts (+0 failed), 383 URLs
2015-08-17 14:19:22,025 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 8 Package Description Documents (+0 failed)
2015-08-17 14:19:22,025 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 14:19:22,076 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Spring Surf Container Web Script Container (in 899.57837ms)
2015-08-17 14:19:22,094 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 14:19:22,094 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
Aug 17, 2015 2:19:22 PM org.apache.catalina.core.ApplicationContext log
INFO: org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: loaded (conf ok)
Aug 17, 2015 2:19:22 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring FrameworkServlet 'Spring Surf Dispatcher Servlet'
Aug 17, 2015 2:19:22 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/share.war has finished in 5,711 ms
Aug 17, 2015 2:19:22 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/host-manager
Aug 17, 2015 2:19:22 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/host-manager has finished in 44 ms
Aug 17, 2015 2:19:22 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/manager
Aug 17, 2015 2:19:22 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/manager has finished in 35 ms
Aug 17, 2015 2:19:22 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 2:19:22 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 2:19:22 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 2:19:22 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 43124 ms
2015-08-17 14:20:25,707 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-2] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 14:20:25,708 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-2] Registered 1 Package Description Documents (+0 failed)
2015-08-17 14:20:25,708 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-2] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 14:20:25,709 INFO [extensions.webscripts.AbstractRuntimeContainer] [http-bio-8080-exec-2] Initialised Repository Web Script Container (in 1300.8969ms)
2015-08-17 14:20:26,851 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 14:20:26,851 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 14:20:26,851 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:04:58,827 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-7] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 16:04:58,827 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-7] Registered 1 Package Description Documents (+0 failed)
2015-08-17 16:04:58,827 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-7] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:04:58,829 INFO [extensions.webscripts.AbstractRuntimeContainer] [http-bio-8080-exec-7] Initialised Repository Web Script Container (in 1242.0018ms)
2015-08-17 16:04:59,928 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 16:04:59,928 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 16:04:59,928 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:21:09,553 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 16:21:09,553 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 16:21:09,553 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:21:09,555 INFO [extensions.webscripts.AbstractRuntimeContainer] [http-bio-8080-exec-1] Initialised Repository Web Script Container (in 1278.8969ms)
2015-08-17 16:21:10,691 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 16:21:10,691 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 16:21:10,691 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
Aug 17, 2015 4:29:31 PM org.apache.catalina.core.StandardServer await
INFO: A valid shutdown command was received via the shutdown port. Stopping the Server instance.
Aug 17, 2015 4:29:31 PM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 4:29:31 PM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 4:29:31 PM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 4:29:31 PM org.apache.catalina.core.StandardService stopInternal
INFO: Stopping service Catalina
Aug 17, 2015 4:29:31 PM com.sun.xml.ws.transport.http.servlet.WSServletDelegate destroy
INFO: WSSERVLET15: JAX-WS servlet destroyed
Aug 17, 2015 4:29:31 PM com.sun.xml.ws.transport.http.servlet.WSServletContextListener contextDestroyed
INFO: WSSERVLET13: JAX-WS context listener destroyed
Aug 17, 2015 4:29:31 PM org.apache.catalina.core.ApplicationContext log
INFO: Closing Spring root WebApplicationContext
2015-08-17 16:29:31,984 INFO [cluster.core.ClusteringBootstrap] [localhost-startStop-2] Clustering has shutdown.
2015-08-17 16:29:32,003 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 16:29:32,005 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 16:29:32,014 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Replication' subsystem, ID: [Replication, default]
2015-08-17 16:29:32,015 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Replication' subsystem, ID: [Replication, default]
2015-08-17 16:29:32,025 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 16:29:32,025 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 16:29:32,026 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'email' subsystem, ID: [email, inbound]
2015-08-17 16:29:32,027 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'email' subsystem, ID: [email, inbound]
2015-08-17 16:29:32,027 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 16:29:32,041 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 16:29:32,041 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
Aug 17, 2015 4:29:32 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager stop
INFO: stopping
Aug 17, 2015 4:29:32 PM org.artofsolving.jodconverter.office.OfficeConnection$1 disposing
INFO: disconnected: 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1'
Aug 17, 2015 4:29:32 PM org.artofsolving.jodconverter.office.ManagedOfficeProcess doEnsureProcessExited
INFO: process exited with code 0
Aug 17, 2015 4:29:32 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager stop
INFO: stopped
2015-08-17 16:29:32,319 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
2015-08-17 16:29:32,337 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 16:29:32,338 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 16:29:32,338 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
2015-08-17 16:29:32,339 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
2015-08-17 16:29:32,339 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 16:29:32,340 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 16:29:32,340 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 16:29:32,340 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 16:29:32,343 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 16:29:32,343 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 16:29:32,344 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'email' subsystem, ID: [email, outbound]
2015-08-17 16:29:32,344 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'email' subsystem, ID: [email, outbound]
2015-08-17 16:29:32,345 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'imap' subsystem, ID: [imap, default]
2015-08-17 16:29:32,346 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'imap' subsystem, ID: [imap, default]
2015-08-17 16:29:32,742 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 16:29:33,029 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 16:29:33,089 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
2015-08-17 16:29:33,090 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
2015-08-17 16:29:33,091 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 16:29:33,092 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 16:29:33,092 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
2015-08-17 16:29:33,094 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 16:29:33,094 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 16:29:33,095 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
2015-08-17 16:29:33,095 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 16:29:33,096 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 16:29:33,098 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 17, 2015 4:29:33 PM org.apache.catalina.core.ApplicationContext log
INFO: Shutting down log4j
Aug 17, 2015 4:29:33 PM org.apache.catalina.core.ApplicationContext log
INFO: Destroying Spring FrameworkServlet 'Spring Surf Dispatcher Servlet'
Aug 17, 2015 4:29:33 PM org.apache.catalina.core.ApplicationContext log
INFO: org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: destroy called
Aug 17, 2015 4:29:33 PM org.apache.catalina.core.ApplicationContext log
INFO: Closing Spring root WebApplicationContext
Aug 17, 2015 4:30:04 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 4:30:04 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 4:30:04 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 4:30:04 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 4:30:04 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 4:30:04 PM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 4:30:19 PM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: /Users/vthakur/Library/Java/Extensions:/Library/Java/Extensions:/Network/Library/Java/Extensions:/System/Library/Java/Extensions:/usr/lib/java:.
Aug 17, 2015 4:30:19 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 4:30:19 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 4:30:19 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 4:30:19 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 496 ms
Aug 17, 2015 4:30:19 PM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Aug 17, 2015 4:30:19 PM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.59
Aug 17, 2015 4:30:19 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor /Applications/alfresco-5.0.1.5/tomcat/conf/Catalina/localhost/solr4.xml
Aug 17, 2015 4:30:19 PM org.apache.catalina.startup.SetContextPropertiesRule begin
WARNING: [SetContextPropertiesRule]{Context} Setting property 'debug' to '0' did not find a matching property.
Aug 17, 2015 4:30:22 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
2015-08-17 16:30:24,539 INFO [solr.component.AsyncBuildSuggestComponent] [coreLoadExecutor-5-thread-1] Initializing SuggestComponent
2015-08-17 16:30:24,539 INFO [solr.component.AsyncBuildSuggestComponent] [coreLoadExecutor-5-thread-2] Initializing SuggestComponent
2015-08-17 16:30:24,615 INFO [solr.component.AsyncBuildSuggestComponent] [Suggestor-alfresco-1] Loading suggester index for: shingleBasedSuggestions
Aug 17, 2015 4:30:24 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deployment of configuration descriptor /Applications/alfresco-5.0.1.5/tomcat/conf/Catalina/localhost/solr4.xml has finished in 5,077 ms
Aug 17, 2015 4:30:24 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/_vti_bin.war
Aug 17, 2015 4:30:24 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/_vti_bin.war has finished in 92 ms
Aug 17, 2015 4:30:24 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/alfresco.war
2015-08-17 16:30:24,844 INFO [solr.component.AsyncBuildSuggestComponent] [Suggestor-alfresco-1] Loaded suggester shingleBasedSuggestions, took 228 ms
Aug 17, 2015 4:30:29 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core_rt is already defined
Aug 17, 2015 4:30:29 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core is already defined
Aug 17, 2015 4:30:29 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/core is already defined
Aug 17, 2015 4:30:29 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/fmt_rt is already defined
Aug 17, 2015 4:30:29 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/fmt is already defined
Aug 17, 2015 4:30:29 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/fmt is already defined
Aug 17, 2015 4:30:29 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/functions is already defined
Aug 17, 2015 4:30:29 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/standard/permittedTaglibs is already defined
Aug 17, 2015 4:30:29 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/standard/scriptfree is already defined
Aug 17, 2015 4:30:29 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/sql_rt is already defined
Aug 17, 2015 4:30:29 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/sql is already defined
Aug 17, 2015 4:30:29 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/sql is already defined
Aug 17, 2015 4:30:29 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/xml_rt is already defined
Aug 17, 2015 4:30:29 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/xml is already defined
Aug 17, 2015 4:30:29 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/xml is already defined
Aug 17, 2015 4:30:29 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Aug 17, 2015 4:30:29 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2015-08-17 16:30:31,884 INFO [alfresco.repo.admin] [localhost-startStop-1] Using database URL 'jdbcostgresql://localhost:5432/alfresco' with user 'alfresco'.
2015-08-17 16:30:32,101 INFO [alfresco.repo.admin] [localhost-startStop-1] Connected to database PostgreSQL version 9.3.6
2015-08-17 16:30:36,182 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 16:30:36,204 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
2015-08-17 16:30:36,213 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Ignoring script patch (post-Hibernate): patch.db-V4.2-metadata-query-indexes
2015-08-17 16:30:41,814 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor Repository Template Processor for extension ftl
2015-08-17 16:30:41,816 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor Repository Script Processor for extension js
2015-08-17 16:30:43,454 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 16:30:43,474 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted] complete
2015-08-17 16:30:43,516 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Connecting to database: jdbcostgresql://localhost:5432/alfresco, UserName=alfresco, PostgreSQL Native Driver
2015-08-17 16:30:43,517 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Schema managed by database dialect org.hibernate.dialect.PostgreSQLDialect.
2015-08-17 16:30:44,825 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] No changes were made to the schema.
2015-08-17 16:30:45,472 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 16:30:45,573 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
2015-08-17 16:30:46,068 INFO [repo.sync.SyncAdminServiceImpl] [localhost-startStop-1] There is no key for cloud sync, cloud sync turned off
2015-08-17 16:30:46,077 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 16:30:46,158 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
2015-08-17 16:30:46,158 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 16:30:46,202 WARN [alfresco.util.AbstractTriggerBean] [localhost-startStop-1] Job openOfficeConnectionTesterTrigger is not enabled
2015-08-17 16:30:46,317 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
2015-08-17 16:30:46,317 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
Aug 17, 2015 4:30:46 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager <init>
INFO: ProcessManager implementation is PureJavaProcessManager
Aug 17, 2015 4:30:46 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Using GNU based LibreOffice command on Mac: [/Applications/alfresco-5.0.1.5/libreoffice.app/Contents/MacOS/soffice, –accept=socket,host=127.0.0.1,port=8100;urp;, -env:UserInstallation=file:///Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100, –headless, –nocrashreport, –nodefault, –nofirststartwizard, –nolockcheck, –nologo, –norestore]
Aug 17, 2015 4:30:46 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Using GNU based LibreOffice 3.6 command on Mac: [/Applications/alfresco-5.0.1.5/libreoffice.app/Contents/MacOS/soffice, –accept=socket,host=127.0.0.1,port=8100;urp;, -env:UserInstallation=file:///Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100, –headless, –nocrashreport, –nodefault, –nofirststartwizard, –nolockcheck, –nologo, –norestore]
Aug 17, 2015 4:30:46 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Removing $DYLD_LIBRARY_PATH from the environment so that LibreOffice/OpenOffice will start on Mac.
Aug 17, 2015 4:30:46 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: starting process with acceptString 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1' and profileDir '/Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100'
Aug 17, 2015 4:30:46 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: started process
Aug 17, 2015 4:30:48 PM org.artofsolving.jodconverter.office.OfficeConnection connect
INFO: connected: 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1'
2015-08-17 16:30:48,221 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default] complete
2015-08-17 16:30:48,224 WARN [cluster.core.ClusteringBootstrap] [localhost-startStop-1] License does not permit clustering: clustering is disabled.
2015-08-17 16:30:48,228 INFO [repo.admin.ConfigurationChecker] [localhost-startStop-1] The Alfresco root data directory ('dir.root') is: /Applications/alfresco-5.0.1.5/alf_data
2015-08-17 16:30:48,228 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 16:30:48,443 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Search' subsystem, ID: [Search, managed, solr4] complete
2015-08-17 16:30:48,447 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Checking for patches to apply …
2015-08-17 16:30:48,650 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] No patches were required.
2015-08-17 16:30:48,670 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Found 1 module(s).
2015-08-17 16:30:48,689 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Starting module 'org.alfresco.integrations.google.docs' version 3.0.0.
2015-08-17 16:30:48,692 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 16:30:48,965 WARN [org.alfresco.fileserver] [localhost-startStop-1] CIFS server name is longer than 15 characters, truncated to Administrators-
2015-08-17 16:30:48,976 WARN [org.alfresco.fileserver] [localhost-startStop-1] CIFS, Unable to get local domain/workgroup name, using default of WORKGROUP. This may be due to firewall settings or incorrect <broadcast> setting)
2015-08-17 16:30:48,982 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
2015-08-17 16:30:48,982 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'imap' subsystem, ID: [imap, default]
2015-08-17 16:30:49,000 ERROR [org.alfresco.fileserver] [CIFS Server] [SMB] Server error : org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
2015-08-17 16:30:49,004 ERROR [org.alfresco.fileserver] [CIFS Server] Error from JLAN
org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
   at org.alfresco.jlan.smb.server.nio.NIOCifsConnectionsHandler.initializeHandler(NIOCifsConnectionsHandler.java:259)
   at org.alfresco.jlan.smb.server.SMBServer.run(SMBServer.java:479)
   at java.lang.Thread.run(Thread.java:745)
2015-08-17 16:30:49,063 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'imap' subsystem, ID: [imap, default] complete
2015-08-17 16:30:49,063 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'email' subsystem, ID: [email, outbound]
2015-08-17 16:30:49,114 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'email' subsystem, ID: [email, outbound] complete
2015-08-17 16:30:49,115 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'email' subsystem, ID: [email, inbound]
2015-08-17 16:30:49,156 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'email' subsystem, ID: [email, inbound] complete
2015-08-17 16:30:49,156 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 16:30:49,197 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Subscriptions' subsystem, ID: [Subscriptions, default] complete
2015-08-17 16:30:49,219 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 16:30:49,309 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
2015-08-17 16:30:49,341 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco JVM - v1.8.0_31-b13; maximum heap size 8125.500MB
2015-08-17 16:30:49,358 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco license: Mode ENTERPRISE, NO CLUSTER granted to Trial User limited to 30 days expiring Wed Sep 16 00:00:00 IST 2015 (30 days remaining).
2015-08-17 16:30:49,358 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Server Mode :UNKNOWN
2015-08-17 16:30:49,358 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco started (Enterprise). Current version: 5.0.1 (.5 r108957-b87) schema 8,022. Originally installed version: 5.0.1 (.5 r108957-b87) schema 8,022.
2015-08-17 16:30:49,367 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 16:30:49,529 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default] complete
2015-08-17 16:30:49,530 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Replication' subsystem, ID: [Replication, default]
2015-08-17 16:30:49,542 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Replication' subsystem, ID: [Replication, default] complete
2015-08-17 16:30:50,317 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 16:30:50,382 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'googledocs' subsystem, ID: [googledocs, drive] complete
2015-08-17 16:30:52,705 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 16:30:52,706 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 16:30:52,706 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:30:54,008 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 16:30:54,009 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 16:30:54,009 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:30:54,010 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Repository Web Script Container (in 3599.808ms)
2015-08-17 16:30:54,025 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 16:30:54,026 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 16:30:55,111 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 16:30:55,111 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 16:30:55,112 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:30:55,154 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 16:30:55,154 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 16:30:55,154 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:30:55,226 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 16:30:55,226 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 16:30:55,226 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:30:55,227 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Public Api Web Script Container (in 1161.2935ms)
2015-08-17 16:30:55,255 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 16:30:55,255 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 16:30:55,255 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
Aug 17, 2015 4:30:55 PM com.sun.xml.ws.transport.http.servlet.WSServletContextListener contextInitialized
INFO: WSSERVLET12: JAX-WS context listener initializing
Aug 17, 2015 4:30:57 PM com.sun.xml.ws.transport.http.servlet.WSServletDelegate <init>
INFO: WSSERVLET14: JAX-WS servlet initializing
Aug 17, 2015 4:30:57 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/alfresco.war has finished in 33,054 ms
Aug 17, 2015 4:30:57 PM org.apache.catalina.core.StandardContext setPath
WARNING: A context path must either be an empty string or start with a '/' and do not end with a '/'. The path [/] does not meet these criteria and has been changed to []
Aug 17, 2015 4:30:57 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/ROOT.war
Aug 17, 2015 4:30:57 PM org.apache.catalina.startup.SetContextPropertiesRule begin
WARNING: [SetContextPropertiesRule]{Context} Setting property 'debug' to '100' did not find a matching property.
Aug 17, 2015 4:30:57 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/ROOT.war has finished in 62 ms
Aug 17, 2015 4:30:57 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/share.war
Aug 17, 2015 4:30:59 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Aug 17, 2015 4:30:59 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2015-08-17 16:31:02,273 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 16:31:02,277 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 16:31:02,279 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 16:31:02,282 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 16:31:03,009 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 369 Web Scripts (+0 failed), 383 URLs
2015-08-17 16:31:03,009 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 8 Package Description Documents (+0 failed)
2015-08-17 16:31:03,009 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:31:03,046 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Spring Surf Container Web Script Container (in 764.57184ms)
2015-08-17 16:31:03,062 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 16:31:03,063 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
Aug 17, 2015 4:31:03 PM org.apache.catalina.core.ApplicationContext log
INFO: org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: loaded (conf ok)
Aug 17, 2015 4:31:03 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring FrameworkServlet 'Spring Surf Dispatcher Servlet'
Aug 17, 2015 4:31:03 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/share.war has finished in 5,371 ms
Aug 17, 2015 4:31:03 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/host-manager
Aug 17, 2015 4:31:03 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/host-manager has finished in 49 ms
Aug 17, 2015 4:31:03 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/manager
Aug 17, 2015 4:31:03 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/manager has finished in 34 ms
Aug 17, 2015 4:31:03 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 4:31:03 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 4:31:03 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 4:31:03 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 43790 ms
2015-08-17 16:32:36,276 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 16:32:36,276 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 16:32:36,276 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:32:36,277 INFO [extensions.webscripts.AbstractRuntimeContainer] [http-bio-8080-exec-1] Initialised Repository Web Script Container (in 1338.8623ms)
2015-08-17 16:32:37,437 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 16:32:37,438 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 16:32:37,438 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:32:44,115 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-4] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 16:32:44,116 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-4] Registered 1 Package Description Documents (+0 failed)
2015-08-17 16:32:44,116 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-4] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:32:44,117 INFO [extensions.webscripts.AbstractRuntimeContainer] [http-bio-8080-exec-4] Initialised Repository Web Script Container (in 1201.9731ms)
2015-08-17 16:32:45,281 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 16:32:45,281 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 16:32:45,281 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
Aug 17, 2015 4:38:13 PM org.apache.catalina.core.StandardServer await
INFO: A valid shutdown command was received via the shutdown port. Stopping the Server instance.
Aug 17, 2015 4:38:13 PM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 4:38:13 PM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 4:38:13 PM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 4:38:13 PM org.apache.catalina.core.StandardService stopInternal
INFO: Stopping service Catalina
Aug 17, 2015 4:38:13 PM com.sun.xml.ws.transport.http.servlet.WSServletDelegate destroy
INFO: WSSERVLET15: JAX-WS servlet destroyed
Aug 17, 2015 4:38:13 PM com.sun.xml.ws.transport.http.servlet.WSServletContextListener contextDestroyed
INFO: WSSERVLET13: JAX-WS context listener destroyed
Aug 17, 2015 4:38:13 PM org.apache.catalina.core.ApplicationContext log
INFO: Closing Spring root WebApplicationContext
2015-08-17 16:38:13,921 INFO [cluster.core.ClusteringBootstrap] [localhost-startStop-2] Clustering has shutdown.
2015-08-17 16:38:13,947 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 16:38:13,949 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 16:38:13,957 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Replication' subsystem, ID: [Replication, default]
2015-08-17 16:38:13,957 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Replication' subsystem, ID: [Replication, default]
2015-08-17 16:38:13,970 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 16:38:13,971 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 16:38:13,971 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'email' subsystem, ID: [email, inbound]
2015-08-17 16:38:13,972 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'email' subsystem, ID: [email, inbound]
2015-08-17 16:38:13,972 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 16:38:13,976 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 16:38:13,977 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
Aug 17, 2015 4:38:13 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager stop
INFO: stopping
Aug 17, 2015 4:38:13 PM org.artofsolving.jodconverter.office.OfficeConnection$1 disposing
INFO: disconnected: 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1'
Aug 17, 2015 4:38:14 PM org.artofsolving.jodconverter.office.ManagedOfficeProcess doEnsureProcessExited
INFO: process exited with code 0
Aug 17, 2015 4:38:14 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager stop
INFO: stopped
2015-08-17 16:38:14,252 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
2015-08-17 16:38:14,267 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 16:38:14,267 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 16:38:14,268 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
2015-08-17 16:38:14,268 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
2015-08-17 16:38:14,269 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 16:38:14,269 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 16:38:14,269 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 16:38:14,270 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 16:38:14,280 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 16:38:14,281 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 16:38:14,281 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'email' subsystem, ID: [email, outbound]
2015-08-17 16:38:14,281 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'email' subsystem, ID: [email, outbound]
2015-08-17 16:38:14,282 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'imap' subsystem, ID: [imap, default]
2015-08-17 16:38:14,283 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'imap' subsystem, ID: [imap, default]
2015-08-17 16:38:14,737 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 16:38:15,037 ERROR [solr.tracker.AbstractTracker] [SolrTrackerScheduler_Worker-1] Tracking failed
org.alfresco.error.AlfrescoRuntimeException: 07170001 api/solr/aclchangesets return status:503
   at org.alfresco.solr.client.SOLRAPIClient.getAclChangeSets(SOLRAPIClient.java:159)
   at org.alfresco.solr.tracker.AclTracker.checkRepoAndIndexConsistency(AclTracker.java:347)
   at org.alfresco.solr.tracker.AclTracker.trackRepository(AclTracker.java:313)
   at org.alfresco.solr.tracker.AclTracker.doTrack(AclTracker.java:104)
   at org.alfresco.solr.tracker.AbstractTracker.track(AbstractTracker.java:152)
   at org.alfresco.solr.tracker.TrackerJob.execute(TrackerJob.java:47)
   at org.quartz.core.JobRunShell.run(JobRunShell.java:216)
   at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:563)
2015-08-17 16:38:15,040 ERROR [solr.tracker.AbstractTracker] [SolrTrackerScheduler_Worker-3] Tracking failed
org.alfresco.error.AlfrescoRuntimeException: 07170000 api/solr/aclchangesets return status:503
   at org.alfresco.solr.client.SOLRAPIClient.getAclChangeSets(SOLRAPIClient.java:159)
   at org.alfresco.solr.tracker.AclTracker.checkRepoAndIndexConsistency(AclTracker.java:347)
   at org.alfresco.solr.tracker.AclTracker.trackRepository(AclTracker.java:313)
   at org.alfresco.solr.tracker.AclTracker.doTrack(AclTracker.java:104)
   at org.alfresco.solr.tracker.AbstractTracker.track(AbstractTracker.java:152)
   at org.alfresco.solr.tracker.TrackerJob.execute(TrackerJob.java:47)
   at org.quartz.core.JobRunShell.run(JobRunShell.java:216)
   at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:563)
2015-08-17 16:38:15,053 ERROR [solr.tracker.AbstractTracker] [SolrTrackerScheduler_Worker-3] Tracking failed
org.alfresco.error.AlfrescoRuntimeException: 07170002 GetModelsDiff return status is 503
   at org.alfresco.solr.client.SOLRAPIClient.getModelsDiff(SOLRAPIClient.java:1091)
   at org.alfresco.solr.tracker.ModelTracker.trackModelsImpl(ModelTracker.java:245)
   at org.alfresco.solr.tracker.ModelTracker.trackModels(ModelTracker.java:217)
   at org.alfresco.solr.tracker.ModelTracker.doTrack(ModelTracker.java:167)
   at org.alfresco.solr.tracker.AbstractTracker.track(AbstractTracker.java:152)
   at org.alfresco.solr.tracker.TrackerJob.execute(TrackerJob.java:47)
   at org.quartz.core.JobRunShell.run(JobRunShell.java:216)
   at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:563)
2015-08-17 16:38:15,206 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 16:38:15,268 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
2015-08-17 16:38:15,269 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
2015-08-17 16:38:15,270 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 16:38:15,271 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 16:38:15,271 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
2015-08-17 16:38:15,272 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 16:38:15,273 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 16:38:15,273 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
2015-08-17 16:38:15,274 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopping 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 16:38:15,275 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-2] Stopped 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 16:38:15,277 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 17, 2015 4:38:15 PM org.apache.catalina.core.ApplicationContext log
INFO: Shutting down log4j
Aug 17, 2015 4:38:15 PM org.apache.catalina.core.ApplicationContext log
INFO: Destroying Spring FrameworkServlet 'Spring Surf Dispatcher Servlet'
Aug 17, 2015 4:38:15 PM org.apache.catalina.core.ApplicationContext log
INFO: org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: destroy called
Aug 17, 2015 4:38:15 PM org.apache.catalina.core.ApplicationContext log
INFO: Closing Spring root WebApplicationContext
Aug 17, 2015 4:39:33 PM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: /Users/vthakur/Library/Java/Extensions:/Library/Java/Extensions:/Network/Library/Java/Extensions:/System/Library/Java/Extensions:/usr/lib/java:.
Aug 17, 2015 4:39:34 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 4:39:34 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 4:39:34 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 4:39:34 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 469 ms
Aug 17, 2015 4:39:34 PM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Aug 17, 2015 4:39:34 PM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.59
Aug 17, 2015 4:39:34 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor /Applications/alfresco-5.0.1.5/tomcat/conf/Catalina/localhost/solr4.xml
Aug 17, 2015 4:39:34 PM org.apache.catalina.startup.SetContextPropertiesRule begin
WARNING: [SetContextPropertiesRule]{Context} Setting property 'debug' to '0' did not find a matching property.
Aug 17, 2015 4:39:37 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
2015-08-17 16:39:39,287 INFO [solr.component.AsyncBuildSuggestComponent] [coreLoadExecutor-5-thread-1] Initializing SuggestComponent
2015-08-17 16:39:39,288 INFO [solr.component.AsyncBuildSuggestComponent] [coreLoadExecutor-5-thread-2] Initializing SuggestComponent
2015-08-17 16:39:39,382 INFO [solr.component.AsyncBuildSuggestComponent] [Suggestor-alfresco-1] Loading suggester index for: shingleBasedSuggestions
Aug 17, 2015 4:39:39 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deployment of configuration descriptor /Applications/alfresco-5.0.1.5/tomcat/conf/Catalina/localhost/solr4.xml has finished in 5,144 ms
Aug 17, 2015 4:39:39 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/_vti_bin.war
Aug 17, 2015 4:39:39 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/_vti_bin.war has finished in 89 ms
Aug 17, 2015 4:39:39 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/alfresco.war
2015-08-17 16:39:39,655 INFO [solr.component.AsyncBuildSuggestComponent] [Suggestor-alfresco-1] Loaded suggester shingleBasedSuggestions, took 272 ms
Aug 17, 2015 4:39:44 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core_rt is already defined
Aug 17, 2015 4:39:44 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core is already defined
Aug 17, 2015 4:39:44 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/core is already defined
Aug 17, 2015 4:39:44 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/fmt_rt is already defined
Aug 17, 2015 4:39:44 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/fmt is already defined
Aug 17, 2015 4:39:44 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/fmt is already defined
Aug 17, 2015 4:39:44 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/functions is already defined
Aug 17, 2015 4:39:44 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/standard/permittedTaglibs is already defined
Aug 17, 2015 4:39:44 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/standard/scriptfree is already defined
Aug 17, 2015 4:39:44 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/sql_rt is already defined
Aug 17, 2015 4:39:44 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/sql is already defined
Aug 17, 2015 4:39:44 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/sql is already defined
Aug 17, 2015 4:39:44 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/xml_rt is already defined
Aug 17, 2015 4:39:44 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/xml is already defined
Aug 17, 2015 4:39:44 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/xml is already defined
Aug 17, 2015 4:39:44 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Aug 17, 2015 4:39:44 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2015-08-17 16:39:46,799 INFO [alfresco.repo.admin] [localhost-startStop-1] Using database URL 'jdbcostgresql://localhost:5432/alfresco' with user 'alfresco'.
2015-08-17 16:39:46,919 INFO [alfresco.repo.admin] [localhost-startStop-1] Connected to database PostgreSQL version 9.3.6
2015-08-17 16:39:50,820 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
2015-08-17 16:39:50,841 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
2015-08-17 16:39:50,850 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Ignoring script patch (post-Hibernate): patch.db-V4.2-metadata-query-indexes
2015-08-17 16:39:55,829 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor Repository Template Processor for extension ftl
2015-08-17 16:39:55,831 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor Repository Script Processor for extension js
2015-08-17 16:39:57,313 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted]
2015-08-17 16:39:57,332 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'ContentStore' subsystem, ID: [ContentStore, managed, unencrypted] complete
2015-08-17 16:39:57,378 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Connecting to database: jdbcostgresql://localhost:5432/alfresco, UserName=alfresco, PostgreSQL Native Driver
2015-08-17 16:39:57,378 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] Schema managed by database dialect org.hibernate.dialect.PostgreSQLDialect.
2015-08-17 16:39:58,632 INFO [domain.schema.SchemaBootstrap] [localhost-startStop-1] No changes were made to the schema.
2015-08-17 16:39:59,237 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
2015-08-17 16:39:59,353 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
2015-08-17 16:39:59,881 INFO [repo.sync.SyncAdminServiceImpl] [localhost-startStop-1] There is no key for cloud sync, cloud sync turned off
2015-08-17 16:39:59,890 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
2015-08-17 16:39:59,974 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
2015-08-17 16:39:59,974 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
2015-08-17 16:40:00,020 WARN [alfresco.util.AbstractTriggerBean] [localhost-startStop-1] Job openOfficeConnectionTesterTrigger is not enabled
2015-08-17 16:40:00,133 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
2015-08-17 16:40:00,133 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
Aug 17, 2015 4:40:00 PM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager <init>
INFO: ProcessManager implementation is PureJavaProcessManager
Aug 17, 2015 4:40:00 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Using GNU based LibreOffice command on Mac: [/Applications/alfresco-5.0.1.5/libreoffice.app/Contents/MacOS/soffice, –accept=socket,host=127.0.0.1,port=8100;urp;, -env:UserInstallation=file:///Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100, –headless, –nocrashreport, –nodefault, –nofirststartwizard, –nolockcheck, –nologo, –norestore]
Aug 17, 2015 4:40:00 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Using GNU based LibreOffice 3.6 command on Mac: [/Applications/alfresco-5.0.1.5/libreoffice.app/Contents/MacOS/soffice, –accept=socket,host=127.0.0.1,port=8100;urp;, -env:UserInstallation=file:///Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100, –headless, –nocrashreport, –nodefault, –nofirststartwizard, –nolockcheck, –nologo, –norestore]
Aug 17, 2015 4:40:00 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: Removing $DYLD_LIBRARY_PATH from the environment so that LibreOffice/OpenOffice will start on Mac.
Aug 17, 2015 4:40:00 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: starting process with acceptString 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1' and profileDir '/Applications/alfresco-5.0.1.5/tomcat/temp/.jodconverter_socket_host-127.0.0.1_port-8100'
Aug 17, 2015 4:40:00 PM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: started process
Aug 17, 2015 4:40:01 PM org.artofsolving.jodconverter.office.OfficeConnection connect
INFO: connected: 'socket,host=127.0.0.1,port=8100,tcpNoDelay=1'
2015-08-17 16:40:01,780 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default] complete
2015-08-17 16:40:01,783 WARN [cluster.core.ClusteringBootstrap] [localhost-startStop-1] License does not permit clustering: clustering is disabled.
2015-08-17 16:40:01,787 INFO [repo.admin.ConfigurationChecker] [localhost-startStop-1] The Alfresco root data directory ('dir.root') is: /Applications/alfresco-5.0.1.5/alf_data
2015-08-17 16:40:01,787 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Search' subsystem, ID: [Search, managed, solr4]
2015-08-17 16:40:01,979 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Search' subsystem, ID: [Search, managed, solr4] complete
2015-08-17 16:40:01,984 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Checking for patches to apply …
2015-08-17 16:40:02,211 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] No patches were required.
2015-08-17 16:40:02,227 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Found 1 module(s).
2015-08-17 16:40:02,247 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Starting module 'org.alfresco.integrations.google.docs' version 3.0.0.
2015-08-17 16:40:02,252 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'fileServers' subsystem, ID: [fileServers, default]
2015-08-17 16:40:02,526 WARN [org.alfresco.fileserver] [localhost-startStop-1] CIFS server name is longer than 15 characters, truncated to Administrators-
2015-08-17 16:40:02,537 WARN [org.alfresco.fileserver] [localhost-startStop-1] CIFS, Unable to get local domain/workgroup name, using default of WORKGROUP. This may be due to firewall settings or incorrect <broadcast> setting)
2015-08-17 16:40:02,543 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
2015-08-17 16:40:02,543 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'imap' subsystem, ID: [imap, default]
2015-08-17 16:40:02,560 ERROR [org.alfresco.fileserver] [CIFS Server] [SMB] Server error : org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
2015-08-17 16:40:02,565 ERROR [org.alfresco.fileserver] [CIFS Server] Error from JLAN
org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
   at org.alfresco.jlan.smb.server.nio.NIOCifsConnectionsHandler.initializeHandler(NIOCifsConnectionsHandler.java:259)
   at org.alfresco.jlan.smb.server.SMBServer.run(SMBServer.java:479)
   at java.lang.Thread.run(Thread.java:745)
2015-08-17 16:40:02,615 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'imap' subsystem, ID: [imap, default] complete
2015-08-17 16:40:02,615 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'email' subsystem, ID: [email, outbound]
2015-08-17 16:40:02,664 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'email' subsystem, ID: [email, outbound] complete
2015-08-17 16:40:02,665 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'email' subsystem, ID: [email, inbound]
2015-08-17 16:40:02,703 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'email' subsystem, ID: [email, inbound] complete
2015-08-17 16:40:02,704 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Subscriptions' subsystem, ID: [Subscriptions, default]
2015-08-17 16:40:02,721 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Subscriptions' subsystem, ID: [Subscriptions, default] complete
2015-08-17 16:40:02,738 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
2015-08-17 16:40:02,864 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
2015-08-17 16:40:02,904 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco JVM - v1.8.0_31-b13; maximum heap size 8125.500MB
2015-08-17 16:40:02,930 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco license: Mode ENTERPRISE, NO CLUSTER granted to Trial User limited to 30 days expiring Wed Sep 16 00:00:00 IST 2015 (30 days remaining).
2015-08-17 16:40:02,930 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Server Mode :UNKNOWN
2015-08-17 16:40:02,930 INFO [service.descriptor.DescriptorService] [localhost-startStop-1] Alfresco started (Enterprise). Current version: 5.0.1 (.5 r108957-b87) schema 8,022. Originally installed version: 5.0.1 (.5 r108957-b87) schema 8,022.
2015-08-17 16:40:02,946 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2015-08-17 16:40:03,128 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default] complete
2015-08-17 16:40:03,128 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'Replication' subsystem, ID: [Replication, default]
2015-08-17 16:40:03,138 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'Replication' subsystem, ID: [Replication, default] complete
2015-08-17 16:40:03,892 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'googledocs' subsystem, ID: [googledocs, drive]
2015-08-17 16:40:03,958 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'googledocs' subsystem, ID: [googledocs, drive] complete
2015-08-17 16:40:06,327 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 16:40:06,327 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 16:40:06,327 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:40:07,685 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 16:40:07,685 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 16:40:07,685 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:40:07,689 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Repository Web Script Container (in 3701.9739ms)
2015-08-17 16:40:07,704 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 16:40:07,706 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 16:40:08,926 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 16:40:08,927 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 16:40:08,927 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:40:08,972 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 16:40:08,972 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 16:40:08,972 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:40:09,005 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 16:40:09,005 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 16:40:09,005 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:40:09,007 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Public Api Web Script Container (in 1257.5306ms)
2015-08-17 16:40:09,043 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 11 Web Scripts (+0 failed), 82 URLs
2015-08-17 16:40:09,043 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed)
2015-08-17 16:40:09,043 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)
Aug 17, 2015 4:40:09 PM com.sun.xml.ws.transport.http.servlet.WSServletContextListener contextInitialized
INFO: WSSERVLET12: JAX-WS context listener initializing
Aug 17, 2015 4:40:11 PM com.sun.xml.ws.transport.http.servlet.WSServletDelegate <init>
INFO: WSSERVLET14: JAX-WS servlet initializing
Aug 17, 2015 4:40:11 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/alfresco.war has finished in 32,073 ms
Aug 17, 2015 4:40:11 PM org.apache.catalina.core.StandardContext setPath
WARNING: A context path must either be an empty string or start with a '/' and do not end with a '/'. The path [/] does not meet these criteria and has been changed to []
Aug 17, 2015 4:40:11 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/ROOT.war
Aug 17, 2015 4:40:11 PM org.apache.catalina.startup.SetContextPropertiesRule begin
WARNING: [SetContextPropertiesRule]{Context} Setting property 'debug' to '100' did not find a matching property.
Aug 17, 2015 4:40:11 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/ROOT.war has finished in 51 ms
Aug 17, 2015 4:40:11 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/share.war
Aug 17, 2015 4:40:12 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Aug 17, 2015 4:40:12 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2015-08-17 16:40:16,543 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 16:40:16,546 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 16:40:16,547 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 16:40:16,547 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2015-08-17 16:40:17,430 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 369 Web Scripts (+0 failed), 383 URLs
2015-08-17 16:40:17,430 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 8 Package Description Documents (+0 failed)
2015-08-17 16:40:17,430 INFO [extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:40:17,477 INFO [extensions.webscripts.AbstractRuntimeContainer] [localhost-startStop-1] Initialised Spring Surf Container Web Script Container (in 929.5081ms)
2015-08-17 16:40:17,495 INFO [extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2015-08-17 16:40:17,496 INFO [extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
Aug 17, 2015 4:40:17 PM org.apache.catalina.core.ApplicationContext log
INFO: org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: loaded (conf ok)
Aug 17, 2015 4:40:17 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring FrameworkServlet 'Spring Surf Dispatcher Servlet'
Aug 17, 2015 4:40:17 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /Applications/alfresco-5.0.1.5/tomcat/webapps/share.war has finished in 6,030 ms
Aug 17, 2015 4:40:17 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/host-manager
Aug 17, 2015 4:40:17 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/host-manager has finished in 42 ms
Aug 17, 2015 4:40:17 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/manager
Aug 17, 2015 4:40:17 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /Applications/alfresco-5.0.1.5/tomcat/webapps/manager has finished in 34 ms
Aug 17, 2015 4:40:17 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8080"]
Aug 17, 2015 4:40:17 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["ajp-bio-8009"]
Aug 17, 2015 4:40:17 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8443"]
Aug 17, 2015 4:40:17 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 43516 ms
2015-08-17 16:40:25,454 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-3] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 16:40:25,455 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-3] Registered 1 Package Description Documents (+0 failed)
2015-08-17 16:40:25,455 INFO [extensions.webscripts.DeclarativeRegistry] [http-bio-8080-exec-3] Registered 0 Schema Description Documents (+0 failed)
2015-08-17 16:40:25,456 INFO [extensions.webscripts.AbstractRuntimeContainer] [http-bio-8080-exec-3] Initialised Repository Web Script Container (in 1369.0985ms)
2015-08-17 16:40:26,573 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 497 Web Scripts (+0 failed), 649 URLs
2015-08-17 16:40:26,573 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed)
2015-08-17 16:40:26,573 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed)