cancel
Showing results for 
Search instead for 
Did you mean: 

New install, java.lang.reflect.InvocationTargetException

fintan
Champ in-the-making
Champ in-the-making
Hi all

I'm trying to install alfresco 3.4.d on ubuntu 10.04 with tomcat6, oracle java 6 and postgresql 8.4. The database is created but I keep running into the same problem see below.


29-Apr-2011 09:48:32 org.apache.catalina.core.StandardContext addApplicationListener
INFO: The listener "org.apache.myfaces.webapp.StartupServletContextListener" is already configured for this context. The duplicate definition has been ignored.
log4j:ERROR setFile(null,true) call failed.
java.io.FileNotFoundException: alfresco.log (Permission denied)
        at java.io.FileOutputStream.openAppend(Native Method)
        at java.io.FileOutputStream.<init>(FileOutputStream.java:177)
        at java.io.FileOutputStream.<init>(FileOutputStream.java:102)
        at org.apache.log4j.FileAppender.setFile(FileAppender.java:290)
        at org.apache.log4j.FileAppender.activateOptions(FileAppender.java:164)
        at org.apache.log4j.DailyRollingFileAppender.activateOptions(DailyRollingFileAppender.java:216)
        at org.apache.log4j.config.PropertySetter.activate(PropertySetter.java:257)
        at org.apache.log4j.config.PropertySetter.setProperties(PropertySetter.java:133)
        at org.apache.log4j.config.PropertySetter.setProperties(PropertySetter.java:97)
        at org.apache.log4j.PropertyConfigurator.parseAppender(PropertyConfigurator.java:689)
        at org.apache.log4j.PropertyConfigurator.parseCategory(PropertyConfigurator.java:647)
        at org.apache.log4j.PropertyConfigurator.configureRootCategory(PropertyConfigurator.java:544)
        at org.apache.log4j.PropertyConfigurator.doConfigure(PropertyConfigurator.java:440)
        at org.apache.log4j.PropertyConfigurator.doConfigure(PropertyConfigurator.java:476)
        at org.apache.log4j.helpers.OptionConverter.selectAndConfigure(OptionConverter.java:471)
        at org.apache.log4j.LogManager.<clinit>(LogManager.java:125)
        at org.apache.log4j.Logger.getLogger(Logger.java:105)
        at org.apache.commons.logging.impl.Log4JLogger.getLogger(Log4JLogger.java:283)
        at org.apache.commons.logging.impl.Log4JLogger.<init>(Log4JLogger.java:108)
        at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
        at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
        at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
        at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
        at org.apache.commons.logging.impl.LogFactoryImpl.createLogFromClass(LogFactoryImpl.java:1040)
        at org.apache.commons.logging.impl.LogFactoryImpl.discoverLogImplementation(LogFactoryImpl.java:838)
        at org.apache.commons.logging.impl.LogFactoryImpl.newInstance(LogFactoryImpl.java:601)
        at org.apache.commons.logging.impl.LogFactoryImpl.getInstance(LogFactoryImpl.java:333)
        at org.apache.commons.logging.impl.LogFactoryImpl.getInstance(LogFactoryImpl.java:307)
        at org.apache.commons.logging.LogFactory.getLog(LogFactory.java:645)
        at org.apache.myfaces.webapp.StartupServletContextListener.<clinit>(StartupServletContextListener.java:44)
        at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
        at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
        at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
        at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
        at java.lang.Class.newInstance0(Class.java:355)
        at java.lang.Class.newInstance(Class.java:308)
        at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3916)
        at org.apache.catalina.core.StandardContext.start(StandardContext.java:4467)
        at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:791)
        at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:771)
        at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:526)
        at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:905)
        at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:740)
        at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:500)
        at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1277)
        at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:321)
        at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:119)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1053)
        at org.apache.catalina.core.StandardHost.start(StandardHost.java:722)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)
        at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443)
        at org.apache.catalina.core.StandardService.start(StandardService.java:516)
        at org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
        at org.apache.catalina.startup.Catalina.start(Catalina.java:593)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:597)
        at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:289)
        at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:414)
log4j:ERROR Either File or DatePattern options are not set for appender [File].
09:48:37,905  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/repository.properties]
09:48:37,907  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/domain/transaction.properties]
09:48:37,907  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/var/cache/tomcat6/Catalina/localhost/alfresco/WEB-INF/classes/alfresco/module/tests/alfresco-global.properties]
09:48:37,908  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/var/cache/tomcat6/Catalina/localhost/alfresco/WEB-INF/classes/alfresco/module/test/alfresco-global.properties]
09:48:37,908  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from URL [file:/var/lib/tomcat6/shared/classes/alfresco-global.properties]
09:48:37,988  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
09:48:38,085  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
09:48:38,112  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
09:48:55,147  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor Repository Template Processor for extension ftl
09:48:55,150  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor Repository Script Processor for extension js
09:49:13,189  INFO  [domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.PostgreSQLDialect.
09:49:21,957  ERROR [domain.schema.SchemaBootstrap] Failed to dump normalized, pre-upgrade schema to file.  Error: PermGen space
09:49:22,466  INFO  [domain.schema.SchemaBootstrap] Executing database script /tmp/tomcat6-tmp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-922941172197207383.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-RepoTables.sql).
09:49:30,987  INFO  [domain.schema.SchemaBootstrap] Executing database script /tmp/tomcat6-tmp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-2584917135883238868.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-LockTables.sql).
09:49:31,486  INFO  [domain.schema.SchemaBootstrap] Executing database script /tmp/tomcat6-tmp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-4625888635581383926.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-ContentTables.sql).
09:49:32,547  INFO  [domain.schema.SchemaBootstrap] Executing database script /tmp/tomcat6-tmp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-5913372265961148966.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-PropertyValueTables.sql).
09:49:35,737  INFO  [domain.schema.SchemaBootstrap] Executing database script /tmp/tomcat6-tmp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-465250408366108537.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-AuditTables.sql).
09:49:36,820  INFO  [domain.schema.SchemaBootstrap] Executing database script /tmp/tomcat6-tmp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-8520349693516012895.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-AvmTables.sql).
java.lang.reflect.InvocationTargetException

After a reboot

29-Apr-2011 09:59:58 org.apache.catalina.core.StandardContext addApplicationListener
INFO: The listener "org.apache.myfaces.webapp.StartupServletContextListener" is already configured for this context. The duplicate definition has been ignored.
log4j:ERROR setFile(null,true) call failed.
java.io.FileNotFoundException: alfresco.log (Permission denied)
        at java.io.FileOutputStream.openAppend(Native Method)
        at java.io.FileOutputStream.<init>(FileOutputStream.java:177)
        at java.io.FileOutputStream.<init>(FileOutputStream.java:102)
        at org.apache.log4j.FileAppender.setFile(FileAppender.java:290)
        at org.apache.log4j.FileAppender.activateOptions(FileAppender.java:164)
        at org.apache.log4j.DailyRollingFileAppender.activateOptions(DailyRollingFileAppender.java:216)
        at org.apache.log4j.config.PropertySetter.activate(PropertySetter.java:257)
        at org.apache.log4j.config.PropertySetter.setProperties(PropertySetter.java:133)
        at org.apache.log4j.config.PropertySetter.setProperties(PropertySetter.java:97)
        at org.apache.log4j.PropertyConfigurator.parseAppender(PropertyConfigurator.java:689)
        at org.apache.log4j.PropertyConfigurator.parseCategory(PropertyConfigurator.java:647)
        at org.apache.log4j.PropertyConfigurator.configureRootCategory(PropertyConfigurator.java:544)
        at org.apache.log4j.PropertyConfigurator.doConfigure(PropertyConfigurator.java:440)
        at org.apache.log4j.PropertyConfigurator.doConfigure(PropertyConfigurator.java:476)
        at org.apache.log4j.helpers.OptionConverter.selectAndConfigure(OptionConverter.java:471)
        at org.apache.log4j.LogManager.<clinit>(LogManager.java:125)
        at org.apache.log4j.Logger.getLogger(Logger.java:105)
        at org.apache.commons.logging.impl.Log4JLogger.getLogger(Log4JLogger.java:283)
        at org.apache.commons.logging.impl.Log4JLogger.<init>(Log4JLogger.java:108)
        at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
        at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
        at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
        at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
        at org.apache.commons.logging.impl.LogFactoryImpl.createLogFromClass(LogFactoryImpl.java:1040)
        at org.apache.commons.logging.impl.LogFactoryImpl.discoverLogImplementation(LogFactoryImpl.java:838)
        at org.apache.commons.logging.impl.LogFactoryImpl.newInstance(LogFactoryImpl.java:601)
        at org.apache.commons.logging.impl.LogFactoryImpl.getInstance(LogFactoryImpl.java:333)
        at org.apache.commons.logging.impl.LogFactoryImpl.getInstance(LogFactoryImpl.java:307)
        at org.apache.commons.logging.LogFactory.getLog(LogFactory.java:645)
        at org.apache.myfaces.webapp.StartupServletContextListener.<clinit>(StartupServletContextListener.java:44)
        at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
        at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
        at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
        at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
        at java.lang.Class.newInstance0(Class.java:355)
        at java.lang.Class.newInstance(Class.java:308)
        at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3916)
        at org.apache.catalina.core.StandardContext.start(StandardContext.java:4467)
        at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:791)
        at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:771)
        at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:526)
        at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:905)
        at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:740)
        at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:500)
        at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1277)
        at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:321)
        at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:119)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1053)
        at org.apache.catalina.core.StandardHost.start(StandardHost.java:722)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)
        at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443)
        at org.apache.catalina.core.StandardService.start(StandardService.java:516)
        at org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
        at org.apache.catalina.startup.Catalina.start(Catalina.java:593)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:597)
        at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:289)
        at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:414)
log4j:ERROR Either File or DatePattern options are not set for appender [File].
10:00:03,622  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/repository.properties]
10:00:03,661  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/domain/transaction.properties]
10:00:03,661  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/var/cache/tomcat6/Catalina/localhost/alfresco/WEB-INF/classes/alfresco/module/tests/alfresco-global.properties]
10:00:03,705  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/var/cache/tomcat6/Catalina/localhost/alfresco/WEB-INF/classes/alfresco/module/test/alfresco-global.properties]
10:00:03,705  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from URL [file:/var/lib/tomcat6/shared/classes/alfresco-global.properties]
10:00:03,753  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:00:03,850  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
10:00:03,877  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
10:00:20,908  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor Repository Template Processor for extension ftl
10:00:20,910  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor Repository Script Processor for extension js
10:00:40,686  INFO  [domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.PostgreSQLDialect.
10:00:53,906  ERROR [domain.schema.SchemaBootstrap] Failed to dump normalized, pre-upgrade schema to file.  Error: PermGen space
java.lang.reflect.InvocationTargetException

or


16:05:46,970  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/repository.properties]
16:05:46,973  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/domain/transaction.properties]
16:05:46,973  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/var/cache/tomcat6/Catalina/localhost/alfresco/WEB-INF/classes/alfresco/module/tests/alfresco-global.properties]
16:05:46,973  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/var/cache/tomcat6/Catalina/localhost/alfresco/WEB-INF/classes/alfresco/module/test/alfresco-global.properties]
16:05:46,973  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from URL [file:/var/lib/tomcat6/shared/classes/alfresco-global.properties]
16:05:47,012  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
16:05:47,123  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
16:05:47,149  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
16:06:17,477  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor Repository Template Processor for extension ftl
16:06:17,480  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor Repository Script Processor for extension js
16:06:40,232  INFO  [domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.PostgreSQLDialect.
java.lang.reflect.InvocationTargetException

Here's my jvm.cfg

-client IF_SERVER_CLASS -server
#-server KNOWN
-server -Xss1024K -Xms2G -Xmx4G -XX:MaxPermSize=256m -XX:NewSize=1G
-hotspot ALIASED_TO -client
-classic WARN
-native ERROR
-green ERROR
-XX:XX:+UseParallelGC
#-XX:+UseConcMarkSweepGC
#-XX:+CMSIncrementalMode
#-XX:CMSInitiatingOccupancyFraction=80
-XX:+CMSClassUnloadingEnabled
-XX:+CMSPermGenSweepingEnabled

Any pointers would be appreciated. Thanks

  fintan
1 REPLY 1

fintan
Champ in-the-making
Champ in-the-making
I tried dropping the database and removing the data directory and I get the same issue.

10:45:58,117  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/repository.properties]
10:45:58,133  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/domain/transaction.properties]
10:45:58,162  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/mnt/datastore/tomcat/webapps/alfresco/WEB-INF/classes/alfresco/module/tests/alfresco-global.properties]
10:45:58,176  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/mnt/datastore/tomcat/webapps/alfresco/WEB-INF/classes/alfresco/module/test/alfresco-global.properties]
10:45:58,186  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from URL [file:/var/lib/tomcat6/shared/classes/alfresco-global.properties]
10:45:58,305  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:45:58,433  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
10:45:58,469  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
10:46:47,403  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor Repository Template Processor for extension ftl
10:46:47,411  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor Repository Script Processor for extension js
10:47:03,587  INFO  [domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.PostgreSQLDialect.
10:47:04,805  INFO  [domain.schema.SchemaBootstrap] Executing database script /tmp/tomcat6-tmp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-1161818766358456982.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-RepoTables.sql).
10:47:13,688  INFO  [domain.schema.SchemaBootstrap] Executing database script /tmp/tomcat6-tmp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-1541746507291232393.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-LockTables.sql).
10:47:14,233  INFO  [domain.schema.SchemaBootstrap] Executing database script /tmp/tomcat6-tmp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-4177756065724626708.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-ContentTables.sql).
10:47:15,265  INFO  [domain.schema.SchemaBootstrap] Executing database script /tmp/tomcat6-tmp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-2591919535476447839.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-PropertyValueTables.sql).
10:47:17,763  INFO  [domain.schema.SchemaBootstrap] Executing database script /tmp/tomcat6-tmp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-4042831264988689002.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-AuditTables.sql).
10:47:19,064  INFO  [domain.schema.SchemaBootstrap] Executing database script /tmp/tomcat6-tmp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-6879796775503550245.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-AvmTables.sql).
10:47:23,390  INFO  [domain.schema.SchemaBootstrap] Executing database script /tmp/tomcat6-tmp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-4961424544545768229.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-ActivityTables.sql).
10:47:24,675  INFO  [domain.schema.SchemaBootstrap] Executing database script /tmp/tomcat6-tmp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-820962492479944000.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-UsageTables.sql).
10:47:25,082  INFO  [domain.schema.SchemaBootstrap] Executing database script /tmp/tomcat6-tmp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-7278506095910507302.sql (Generated).
10:47:31,738  INFO  [domain.schema.SchemaBootstrap] Executing database script /tmp/tomcat6-tmp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-6385842895800002931.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoPostCreate-JBPM-Extra.sql).
10:47:31,760  INFO  [domain.schema.SchemaBootstrap] All executed statements:  /tmp/tomcat6-tmp/Alfresco/AlfrescoSchema-PostgreSQLDialect-All_Statements-6595961104460350697.sql.
10:48:05,314  INFO  [domain.schema.SchemaBootstrap] Normalized schema dumped to file /tmp/tomcat6-tmp/Alfresco/AlfrescoSchema-PostgreSQLDialect-7743337813104235715.xml.
10:48:05,339  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
10:48:05,415  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
10:48:05,416  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:48:05,416  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
10:48:05,439  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
10:48:06,184 User:System WARN  [repo.dictionary.DictionaryRepositoryBootstrap] StoreRef 'workspace://SpacesStore' does not exist
10:48:15,854 User:System INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
10:48:15,944 User:System INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
10:48:15,944 User:System INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:48:15,945 User:System INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
10:48:18,320 User:System INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
10:48:18,321 User:System INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
10:48:18,501 User:System INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
10:48:18,501 User:System INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:48:18,501 User:System INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
10:48:20,143 User:System WARN  [alfresco.util.OpenOfficeConnectionTester] An initial OpenOffice connection could not be established.
10:48:20,167 User:System INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
10:49:07,552  INFO  [repo.admin.ConfigurationChecker] The Alfresco root data directory ('dir.root') is: /mnt/datastore/tomcat/alfresco/alf_data
10:49:07,727  INFO  [admin.patch.PatchExecuter] Checking for patches to apply …
10:49:10,924  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.4-UsageTables
RESULT:
Script completed
=====================================
10:49:10,924  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.0-ActivityTables
RESULT:
Script completed
=====================================
10:49:10,924  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.2-LockTables
RESULT:
Script completed
=====================================
10:49:10,924  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.2-ContentTables
RESULT:
Script completed
=====================================
10:49:10,924  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.2-PropertyValueTables
RESULT:
Script completed
=====================================
10:49:10,924  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.2-AuditTables
RESULT:
Script completed
=====================================
10:49:10,924  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.2-AvmTables
RESULT:
Script completed
=====================================
10:49:10,925  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.3-JBPM-Extra
RESULT:
Script completed
=====================================
10:49:11,012 User:System INFO  [repo.module.ModuleServiceImpl] Found 0 module(s).
10:49:11,135  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'fileServers' subsystem, ID: [fileServers, default]
10:49:11,201  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
10:49:11,202  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:49:11,203  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
10:49:12,314  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
10:49:12,380  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
10:49:12,381  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:49:12,381  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
10:49:12,743  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
10:49:17,781  ERROR [org.alfresco.fileserver] Failed to get local domain/workgroup name, using default of WORKGROUP
10:49:17,781  ERROR [org.alfresco.fileserver] (This may be due to firewall settings or incorrect <broadcast> setting)
10:49:18,200  ERROR [org.alfresco.fileserver] [SMB] Server error : org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
10:49:18,206  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
10:49:18,206  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'imap' subsystem, ID: [imap, default]
10:49:18,207  ERROR [org.alfresco.fileserver] [FTP] FTP Socket error : java.net.BindException: Permission denied
10:49:18,214  ERROR [org.alfresco.fileserver] org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
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:472)
        at java.lang.Thread.run(Thread.java:662)
10:49:18,214  ERROR [org.alfresco.fileserver] java.net.BindException: Permission denied
java.net.BindException: Permission denied
        at java.net.PlainSocketImpl.socketBind(Native Method)
        at java.net.PlainSocketImpl.bind(PlainSocketImpl.java:383)
        at java.net.ServerSocket.bind(ServerSocket.java:328)
        at java.net.ServerSocket.<init>(ServerSocket.java:194)
        at java.net.ServerSocket.<init>(ServerSocket.java:150)
        at org.alfresco.jlan.ftp.FTPServer.run(FTPServer.java:555)
        at java.lang.Thread.run(Thread.java:662)
10:49:18,262  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
10:49:18,268  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:49:18,268  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
10:49:18,710  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'imap' subsystem, ID: [imap, default] complete
10:49:18,710  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'email' subsystem, ID: [email, outbound]
10:49:18,756  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
10:49:18,756  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:49:18,757  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
10:49:18,978  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'email' subsystem, ID: [email, outbound] complete
10:49:18,978  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'email' subsystem, ID: [email, inbound]
10:49:19,030  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
10:49:19,030  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:49:19,030  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
10:49:19,242  WARN  [springframework.beans.GenericTypeAwarePropertyDescriptor] Invalid JavaBean property 'blockedSenders' being accessed! Ambiguous write methods found next to actually used [public void org.alfresco.email.server.EmailServer.setBlockedSenders(java.util.List)]: [public void org.alfresco.email.server.EmailServer.setBlockedSenders(java.lang.String)]
10:49:19,242  WARN  [springframework.beans.GenericTypeAwarePropertyDescriptor] Invalid JavaBean property 'allowedSenders' being accessed! Ambiguous write methods found next to actually used [public void org.alfresco.email.server.EmailServer.setAllowedSenders(java.util.List)]: [public void org.alfresco.email.server.EmailServer.setAllowedSenders(java.lang.String)]
10:49:19,294  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'email' subsystem, ID: [email, inbound] complete
10:49:19,294  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'googledocs' subsystem, ID: [googledocs, default]
10:49:19,434  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
10:49:19,435  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:49:19,435  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
10:49:21,316  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'googledocs' subsystem, ID: [googledocs, default] complete
10:49:21,324  INFO  [repo.usage.UserUsageTrackingComponent] Enabled - calculate missing user usages …
10:49:21,425  INFO  [repo.usage.UserUsageTrackingComponent] Found 0 users to recalculate
10:49:21,425  INFO  [repo.usage.UserUsageTrackingComponent] … calculated missing usages for 0 users
10:49:21,425  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
10:49:21,502  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
10:49:21,502  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:49:21,502  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
10:49:21,876  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
10:49:22,022  INFO  [alfresco.util.OpenOfficeConnectionTester] The OpenOffice connection was re-established.
10:49:22,194  INFO  [service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_24-b07; maximum heap size 126.438MB
10:49:22,194  WARN  [service.descriptor.DescriptorService] Alfresco JVM - WARNING - maximum heap size 126.438MB is less than recommended 512MB
10:49:22,194  INFO  [service.descriptor.DescriptorService] Alfresco started (Community): Current version 3.4.0 (d 3370) schema 4113 - Originally installed version 3.4.0 (d 3370) schema 4113
10:49:22,195  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Replication' subsystem, ID: [Replication, default]
10:49:22,292  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
10:49:22,292  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:49:22,292  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
10:49:22,308  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Replication' subsystem, ID: [Replication, default] complete
10:52:24,572 User:System INFO  [extensions.webscripts.DeclarativeRegistry] Registered 372 Web Scripts (+0 failed), 613 URLs
10:52:24,572 User:System INFO  [extensions.webscripts.DeclarativeRegistry] Registered 2 Package Description Documents (+0 failed)
10:52:24,572 User:System INFO  [extensions.webscripts.DeclarativeRegistry] Registered 1 Schema Description Documents (+0 failed)
10:52:24,579 User:System INFO  [extensions.webscripts.AbstractRuntimeContainer] Initialised Repository Web Script Container (in 75196.04ms)
10:52:24,626  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
10:52:24,627  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
java.lang.reflect.InvocationTargetException
[code]