cancel
Showing results for 
Search instead for 
Did you mean: 

[Solved] Community 4.0c Nightly Build - Keystore Invalid

benswitzer
Champ in-the-making
Champ in-the-making
Hey all.

Looking for some assistance with getting Alfresco Community 4.0c going.

Installed 4.0c nightly 64 bit Windows on Windows 2008 R2 without Postgres.  I'm aiming to upgrade an existing installation from 3.4d running MySQL.

Install went off without an issue.  Dropped the MySQL connector JAR in the Tomcat lib folder and made the appropriate changes to the alfresco-global.properties.

Starting Alfresco, I get the following in stderr file:
Dec 12, 2011 1:15:12 PM org.apache.catalina.core.AprLifecycleListener init
INFO: Loaded APR based Apache Tomcat Native library 1.1.20.
Dec 12, 2011 1:15:12 PM org.apache.catalina.core.AprLifecycleListener init
INFO: APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true].
Dec 12, 2011 1:15:13 PM org.apache.coyote.http11.Http11AprProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-8080
Dec 12, 2011 1:15:13 PM org.apache.coyote.ajp.AjpAprProtocol init
INFO: Initializing Coyote AJP/1.3 on ajp-8009
Dec 12, 2011 1:15:14 PM org.apache.coyote.http11.Http11Protocol init
INFO: Initializing Coyote HTTP/1.1 on http-8443
Dec 12, 2011 1:15:14 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 2306 ms
Dec 12, 2011 1:15:14 PM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Dec 12, 2011 1:15:14 PM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.29
Dec 12, 2011 1:15:14 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor alfresco.xml
Dec 12, 2011 1:15:16 PM 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.
Dec 12, 2011 1:15:18 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
Dec 12, 2011 1:15:43 PM org.activiti.engine.impl.ProcessEngineImpl <init>
INFO: ProcessEngine default created
Dec 12, 2011 1:16:17 PM org.activiti.engine.impl.ProcessEngineImpl <init>
INFO: ProcessEngine activitiBootstrapEngine created
Dec 12, 2011 1:16:17 PM org.activiti.engine.impl.db.DbSqlSession executeSchemaResource
INFO: performing create on engine with resource org/activiti/db/create/activiti.mysql.create.engine.sql
Dec 12, 2011 1:16:20 PM org.activiti.engine.impl.db.DbSqlSession executeSchemaResource
INFO: performing create on history with resource org/activiti/db/create/activiti.mysql.create.history.sql
Dec 12, 2011 1:16:21 PM org.activiti.engine.impl.db.DbSqlSession executeSchemaResource
INFO: performing create on identity with resource org/activiti/db/create/activiti.mysql.create.identity.sql
Dec 12, 2011 1:16:24 PM org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run
INFO: JobAcquisitionThread starting to acquire jobs
Dec 12, 2011 1:16:24 PM org.apache.catalina.core.StandardContext listenerStart
SEVERE: Exception sending context initialized event to listener instance of class org.alfresco.web.app.ContextLoaderListener
org.alfresco.error.AlfrescoRuntimeException: 11120001 Keystores are invalid
   at org.alfresco.encryption.EncryptionChecker$1.execute(EncryptionChecker.java:71)
   at org.alfresco.encryption.EncryptionChecker$1.execute(EncryptionChecker.java:61)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:388)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:259)
   at org.alfresco.encryption.EncryptionChecker.onBootstrap(EncryptionChecker.java:60)
   at org.springframework.extensions.surf.util.AbstractLifecycleBean.onApplicationEvent(AbstractLifecycleBean.java:56)
   at org.alfresco.repo.management.SafeApplicationEventMulticaster.multicastEventInternal(SafeApplicationEventMulticaster.java:209)
   at org.alfresco.repo.management.SafeApplicationEventMulticaster.multicastEvent(SafeApplicationEventMulticaster.java:180)
   at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:303)
   at org.springframework.context.support.AbstractApplicationContext.finishRefresh(AbstractApplicationContext.java:911)
   at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:428)
   at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:276)
   at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:197)
   at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:47)
   at org.alfresco.web.app.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:63)
   at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4135)
   at org.apache.catalina.core.StandardContext.start(StandardContext.java:4630)
   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:546)
   at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:637)
   at org.apache.catalina.startup.HostConfig.deployDescriptors(HostConfig.java:563)
   at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:498)
   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:785)
   at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)
   at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:445)
   at org.apache.catalina.core.StandardService.start(StandardService.java:519)
   at org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
   at org.apache.catalina.startup.Catalina.start(Catalina.java:581)
   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)
Caused by: org.alfresco.error.AlfrescoRuntimeException: 11120000 Failed to create keystore:
   Location: C:/Alfresco/Alfresco4cNightly/alf_data/keystore/keystore
   Provider: null
   Type:     JCEKS
   at org.alfresco.encryption.AlfrescoKeyStoreImpl.createKey(AlfrescoKeyStoreImpl.java:637)
   at org.alfresco.encryption.AlfrescoKeyStoreImpl.validateKeys(AlfrescoKeyStoreImpl.java:852)
   at org.alfresco.encryption.AlfrescoKeyStoreImpl.validateKeys(AlfrescoKeyStoreImpl.java:185)
   at org.alfresco.encryption.KeyStoreChecker.validateKeyStores(KeyStoreChecker.java:47)
   at org.alfresco.encryption.EncryptionChecker$1.execute(EncryptionChecker.java:66)
   … 38 more
Caused by: java.lang.NullPointerException
   at org.alfresco.encryption.AlfrescoKeyStoreImpl.getSecretKey(AlfrescoKeyStoreImpl.java:732)
   at org.alfresco.encryption.AlfrescoKeyStoreImpl.createKey(AlfrescoKeyStoreImpl.java:631)
   … 42 more
Dec 12, 2011 1:16:24 PM org.apache.catalina.core.StandardContext listenerStart
SEVERE: Exception sending context initialized event to listener instance of class org.alfresco.web.app.ContextListener
org.alfresco.error.AlfrescoRuntimeException: 11120001 Keystores are invalid
   at org.alfresco.encryption.EncryptionChecker$1.execute(EncryptionChecker.java:71)
   at org.alfresco.encryption.EncryptionChecker$1.execute(EncryptionChecker.java:61)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:388)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:259)
   at org.alfresco.encryption.EncryptionChecker.onBootstrap(EncryptionChecker.java:60)
   at org.springframework.extensions.surf.util.AbstractLifecycleBean.onApplicationEvent(AbstractLifecycleBean.java:56)
   at org.alfresco.repo.management.SafeApplicationEventMulticaster.multicastEventInternal(SafeApplicationEventMulticaster.java:209)
   at org.alfresco.repo.management.SafeApplicationEventMulticaster.multicastEvent(SafeApplicationEventMulticaster.java:180)
   at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:303)
   at org.springframework.context.support.AbstractApplicationContext.finishRefresh(AbstractApplicationContext.java:911)
   at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:428)
   at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:276)
   at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:197)
   at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:47)
   at org.alfresco.web.app.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:63)
   at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4135)
   at org.apache.catalina.core.StandardContext.start(StandardContext.java:4630)
   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:546)
   at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:637)
   at org.apache.catalina.startup.HostConfig.deployDescriptors(HostConfig.java:563)
   at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:498)
   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:785)
   at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)
   at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:445)
   at org.apache.catalina.core.StandardService.start(StandardService.java:519)
   at org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
   at org.apache.catalina.startup.Catalina.start(Catalina.java:581)
   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)
Caused by: org.alfresco.error.AlfrescoRuntimeException: 11120000 Failed to create keystore:
   Location: C:/Alfresco/Alfresco4cNightly/alf_data/keystore/keystore
   Provider: null
   Type:     JCEKS
   at org.alfresco.encryption.AlfrescoKeyStoreImpl.createKey(AlfrescoKeyStoreImpl.java:637)
   at org.alfresco.encryption.AlfrescoKeyStoreImpl.validateKeys(AlfrescoKeyStoreImpl.java:852)
   at org.alfresco.encryption.AlfrescoKeyStoreImpl.validateKeys(AlfrescoKeyStoreImpl.java:185)
   at org.alfresco.encryption.KeyStoreChecker.validateKeyStores(KeyStoreChecker.java:47)
   at org.alfresco.encryption.EncryptionChecker$1.execute(EncryptionChecker.java:66)
   … 38 more
Caused by: java.lang.NullPointerException
   at org.alfresco.encryption.AlfrescoKeyStoreImpl.getSecretKey(AlfrescoKeyStoreImpl.java:732)
   at org.alfresco.encryption.AlfrescoKeyStoreImpl.createKey(AlfrescoKeyStoreImpl.java:631)
   … 42 more
Dec 12, 2011 1:16:24 PM com.sun.xml.ws.transport.http.servlet.WSServletContextListener contextInitialized
INFO: WSSERVLET12: JAX-WS context listener initializing
Dec 12, 2011 1:16:27 PM com.sun.xml.ws.transport.http.servlet.WSServletDelegate <init>
INFO: WSSERVLET14: JAX-WS servlet initializing
Dec 12, 2011 1:16:27 PM org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
Dec 12, 2011 1:16:27 PM org.apache.catalina.core.StandardContext start
SEVERE: Context [/alfresco] startup failed due to previous errors
Dec 12, 2011 1:16:27 PM com.sun.xml.ws.transport.http.servlet.WSServletDelegate destroy
INFO: WSSERVLET15: JAX-WS servlet destroyed
Dec 12, 2011 1:16:27 PM com.sun.xml.ws.transport.http.servlet.WSServletContextListener contextDestroyed
INFO: WSSERVLET13: JAX-WS context listener destroyed
Dec 12, 2011 1:16:27 PM org.apache.catalina.core.ApplicationContext log
INFO: Closing Spring root WebApplicationContext
Dec 12, 2011 1:16:27 PM org.apache.catalina.core.ApplicationContext log
INFO: Shutting down log4j
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [SolrWatcherScheduler_Worker-1] but has failed to stop it. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [SolrWatcherScheduler_Worker-2] but has failed to stop it. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [SolrWatcherScheduler_Worker-3] but has failed to stop it. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [Timer-0] but has failed to stop it. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [DefaultScheduler_Worker-1] but has failed to stop it. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [DefaultScheduler_Worker-2] but has failed to stop it. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [DefaultScheduler_Worker-3] but has failed to stop it. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [DefaultScheduler_Worker-4] but has failed to stop it. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [DefaultScheduler_Worker-5] but has failed to stop it. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [DefaultScheduler_Worker-6] but has failed to stop it. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [DefaultScheduler_Worker-7] but has failed to stop it. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [DefaultScheduler_Worker-8] but has failed to stop it. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [DefaultScheduler_Worker-9] but has failed to stop it. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [DefaultScheduler_Worker-10] but has failed to stop it. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [Thread-4] but has failed to stop it. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [JobAcquisitionThread] but has failed to stop it. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [SolrWatcherScheduler_QuartzSchedulerThread] but has failed to stop it. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [DefaultScheduler_QuartzSchedulerThread] but has failed to stop it. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: The web application [/alfresco] created a ThreadLocal with key of type [java.lang.ThreadLocal] (value [java.lang.ThreadLocal@5be5e369]) and a value of type [org.apache.cxf.bus.CXFBusImpl] (value [org.apache.cxf.bus.CXFBusImpl@51b531a1]) but failed to remove it when the web application was stopped. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: The web application [/alfresco] created a ThreadLocal with key of type [java.lang.ThreadLocal] (value [java.lang.ThreadLocal@4ecd6aef]) and a value of type [org.alfresco.util.CachingDateFormat] (value [yyyy-MM-dd]) but failed to remove it when the web application was stopped. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: The web application [/alfresco] created a ThreadLocal with key of type [null] (value [com.sun.xml.bind.v2.ClassFactory$1@10279954]) and a value of type [java.util.WeakHashMap] (value [{class org.alfresco.repo.audit.model._3.DataExtractor=java.lang.ref.WeakReference@797be2c9, class java.util.ArrayList=java.lang.ref.WeakReference@3466386e, class org.alfresco.repo.audit.model._3.Audit=java.lang.ref.WeakReference@2302d8a2, class org.alfresco.repo.audit.model._3.AuditPath=java.lang.ref.WeakReference@fdc85a3, class org.alfresco.repo.audit.model._3.DataGenerator=java.lang.ref.WeakReference@56c99877, class org.alfresco.repo.audit.model._3.DataExtractors=java.lang.ref.WeakReference@4d38d924, class org.alfresco.repo.audit.model._3.Application=java.lang.ref.WeakReference@4f37f217, class org.alfresco.repo.audit.model._3.RecordValue=java.lang.ref.WeakReference@61f6eca2, class javax.xml.bind.annotation.W3CDomHandler=java.lang.ref.WeakReference@1a29b1ed, class org.alfresco.repo.audit.model._3.PathMap=java.lang.ref.WeakReference@27604c06, class org.alfresco.repo.audit.model._3.GenerateValue=java.lang.ref.WeakReference@231f321c, class org.alfresco.repo.audit.model._3.DataGenerators=java.lang.ref.WeakReference@5512de47, class org.alfresco.repo.audit.model._3.PathMappings=java.lang.ref.WeakReference@49bf32f3}]) but failed to remove it when the web application was stopped. This is very likely to create a memory leak.
Dec 12, 2011 1:16:27 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: The web application [/alfresco] created a ThreadLocal with key of type [null] (value [com.sun.xml.bind.v2.runtime.Coordinator$1@27aa1558]) and a value of type [java.lang.Object[]] (value [[Ljava.lang.Object;@f5f58c0]) but failed to remove it when the web application was stopped. This is very likely to create a memory leak.

And I see this in the stdout debug file:
2011-12-12 13:15:32,882  INFO  [management.subsystems.ChildApplicationContextFactory] [Thread-1] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
2011-12-12 13:15:32,960  INFO  [management.subsystems.ChildApplicationContextFactory] [Thread-1] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
2011-12-12 13:15:44,617  WARN  [alfresco.util.AbstractTriggerBean] [Thread-1] Job ehCacheTracerJob is not active/enabled
2011-12-12 13:15:49,367  INFO  [extensions.webscripts.TemplateProcessorRegistry] [Thread-1] Registered template processor Repository Template Processor for extension ftl
2011-12-12 13:15:49,367  INFO  [extensions.webscripts.ScriptProcessorRegistry] [Thread-1] Registered script processor Repository Script Processor for extension js
2011-12-12 13:15:58,523  INFO  [domain.schema.SchemaBootstrap] [Thread-1] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
2011-12-12 13:15:58,695  WARN  [domain.schema.SchemaBootstrap] [Thread-1] Schema validation found 43 potential problems, results written to: C:\Windows\TEMP\Alfresco\Alfresco-MySQLInnoDBDialect-Validation-Pre-Upgrade-6949221782461038807.txt
2011-12-12 13:15:58,804  INFO  [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-8941091242154205743.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoCreate-RepoTables.sql).
2011-12-12 13:16:00,210  INFO  [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-4474433416366339804.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoCreate-LockTables.sql).
2011-12-12 13:16:00,382  INFO  [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-459675108673634554.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoCreate-ContentTables.sql).
2011-12-12 13:16:00,695  INFO  [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-3253733244680908173.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoCreate-PropertyValueTables.sql).
2011-12-12 13:16:01,273  INFO  [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-3405094446118234059.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoCreate-AuditTables.sql).
2011-12-12 13:16:01,492  INFO  [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-4296386848632861775.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoCreate-AvmTables.sql).
2011-12-12 13:16:04,914  INFO  [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-591537587020060152.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoCreate-ActivityTables.sql).
2011-12-12 13:16:05,101  INFO  [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-3503984441032797361.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoCreate-UsageTables.sql).
2011-12-12 13:16:05,179  INFO  [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-970351876971733832.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoCreate-SubscriptionTables.sql).
2011-12-12 13:16:05,351  INFO  [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-2125769827641612673.sql (Generated).
2011-12-12 13:16:17,273  INFO  [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-1250609668051133307.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoPostCreate-JBPM-Extra.sql).
2011-12-12 13:16:17,320  INFO  [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-2701065513052834605.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoPostCreate-JBPM-FK-indexes.sql).
2011-12-12 13:16:22,507  INFO  [domain.schema.SchemaBootstrap] [Thread-1] All executed statements:  C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-All_Statements-7114401463678228118.sql.
2011-12-12 13:16:23,257  INFO  [domain.schema.SchemaBootstrap] [Thread-1] Compared database schema with reference schema (all OK): class path resource [alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/Schema-Reference.xml]
2011-12-12 13:16:24,195  INFO  [domain.schema.SchemaBootstrap] [Thread-1] Normalized schema dumped to file C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-4333764289648539773.xml.
2011-12-12 13:16:24,320  ERROR [web.context.ContextLoader] [Thread-1] Context initialization failed
org.alfresco.error.AlfrescoRuntimeException: 11120001 Keystores are invalid
   at org.alfresco.encryption.EncryptionChecker$1.execute(EncryptionChecker.java:71)
   at org.alfresco.encryption.EncryptionChecker$1.execute(EncryptionChecker.java:61)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:388)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:259)
   at org.alfresco.encryption.EncryptionChecker.onBootstrap(EncryptionChecker.java:60)
   at org.springframework.extensions.surf.util.AbstractLifecycleBean.onApplicationEvent(AbstractLifecycleBean.java:56)
   at org.alfresco.repo.management.SafeApplicationEventMulticaster.multicastEventInternal(SafeApplicationEventMulticaster.java:209)
   at org.alfresco.repo.management.SafeApplicationEventMulticaster.multicastEvent(SafeApplicationEventMulticaster.java:180)
   at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:303)
   at org.springframework.context.support.AbstractApplicationContext.finishRefresh(AbstractApplicationContext.java:911)
   at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:428)
   at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:276)
   at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:197)
   at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:47)
   at org.alfresco.web.app.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:63)
   at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4135)
   at org.apache.catalina.core.StandardContext.start(StandardContext.java:4630)
   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:546)
   at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:637)
   at org.apache.catalina.startup.HostConfig.deployDescriptors(HostConfig.java:563)
   at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:498)
   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:785)
   at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)
   at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:445)
   at org.apache.catalina.core.StandardService.start(StandardService.java:519)
   at org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
   at org.apache.catalina.startup.Catalina.start(Catalina.java:581)
   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)
Caused by: org.alfresco.error.AlfrescoRuntimeException: 11120000 Failed to create keystore:
   Location: C:/Alfresco/Alfresco4cNightly/alf_data/keystore/keystore
   Provider: null
   Type:     JCEKS
   at org.alfresco.encryption.AlfrescoKeyStoreImpl.createKey(AlfrescoKeyStoreImpl.java:637)
   at org.alfresco.encryption.AlfrescoKeyStoreImpl.validateKeys(AlfrescoKeyStoreImpl.java:852)
   at org.alfresco.encryption.AlfrescoKeyStoreImpl.validateKeys(AlfrescoKeyStoreImpl.java:185)
   at org.alfresco.encryption.KeyStoreChecker.validateKeyStores(KeyStoreChecker.java:47)
   at org.alfresco.encryption.EncryptionChecker$1.execute(EncryptionChecker.java:66)
   … 38 more
Caused by: java.lang.NullPointerException
   at org.alfresco.encryption.AlfrescoKeyStoreImpl.getSecretKey(AlfrescoKeyStoreImpl.java:732)
   at org.alfresco.encryption.AlfrescoKeyStoreImpl.createKey(AlfrescoKeyStoreImpl.java:631)
   … 42 more
log4j:ERROR LogMananger.repositorySelector was null likely due to error in class reloading, using NOPLoggerRepository.
Exception in thread "JobAcquisitionThread" java.lang.NullPointerException
   at org.activiti.engine.impl.jobexecutor.JobAcquisitionThread.run(JobAcquisitionThread.java:103)
2011-12-12 13:16:38,070  INFO  [extensions.webscripts.TemplateProcessorRegistry] [Thread-1] Registered template processor freemarker for extension ftl
2011-12-12 13:16:38,195  INFO  [extensions.webscripts.ScriptProcessorRegistry] [Thread-1] Registered script processor javascript for extension js
2011-12-12 13:16:38,195  INFO  [extensions.webscripts.TemplateProcessorRegistry] [Thread-1] Registered template processor freemarker for extension ftl
2011-12-12 13:16:38,195  INFO  [extensions.webscripts.ScriptProcessorRegistry] [Thread-1] Registered script processor javascript for extension js
2011-12-12 13:16:42,039  INFO  [extensions.webscripts.DeclarativeRegistry] [Thread-1] Registered 312 Web Scripts (+0 failed), 322 URLs
2011-12-12 13:16:42,039  INFO  [extensions.webscripts.DeclarativeRegistry] [Thread-1] Registered 8 Package Description Documents (+0 failed)
2011-12-12 13:16:42,039  INFO  [extensions.webscripts.DeclarativeRegistry] [Thread-1] Registered 0 Schema Description Documents (+0 failed)
2011-12-12 13:16:42,320  INFO  [extensions.webscripts.AbstractRuntimeContainer] [Thread-1] Initialised Spring Surf Container Web Script Container (in 4110.718ms)
2011-12-12 13:16:42,320  INFO  [extensions.webscripts.TemplateProcessorRegistry] [Thread-1] Registered template processor freemarker for extension ftl
2011-12-12 13:16:42,335  INFO  [extensions.webscripts.ScriptProcessorRegistry] [Thread-1] Registered script processor javascript for extension js
log4j:WARN No appenders could be found for logger (org.quartz.core.QuartzSchedulerThread).
log4j:WARN Please initialize the log4j system properly.

Ok then, so perhaps I have to regenerate the keystores.  mrogers makes note of that here: https://forums.alfresco.com/en/viewtopic.php?f=8&t=41514.  Oddly enough, there is no mention in either the install or upgrade guides this has to be done.

Following the directions below, I attempt to create the necessary certs and keystores (Had to grab and install OpenSSL first):
Instructions for Generating Repository SSL Keystores
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~

<store password> is the keystore password. The file ${dir.keystore}/ssl-keystore-passwords.properties contains passwords for the SSL keystore,
${dir.keystore}/ssl-truststore-passwords.properties contains passwords for the SSL truststore.

These instructions will create an RSA public/private key pair for the repository with a certificate that has been signed by the Alfresco Certificate Authority (CA).
It will also create a truststore for the repository containing the CA certificate; this will be used to authenticate connections to specific repository
URLs from Solr. It assumes the existence of the Alfresco CA key and certificate to sign the repository certificate; for security reasons these are not generally available.
You can either generate your own CA key and certificate (see instructions below) or use a recognised Certificate Authority such as Verisign. For Alfresco employees the key
and certificate are available in svn.

(i) Generate the repository public/private key pair in a keystore:

$ keytool -genkey -alias repo -keyalg RSA -keystore ssl.keystore -storetype JCEKS -storepass <store password>
Enter keystore password: 
Re-enter new password:
What is your first and last name?
  [Unknown]:  Alfresco Repository
What is the name of your organizational unit?
  [Unknown]: 
What is the name of your organization?
  [Unknown]:  Alfresco Software Ltd.
What is the name of your City or Locality?
  [Unknown]:  Maidenhead
What is the name of your State or Province?
  [Unknown]:  UK
What is the two-letter country code for this unit?
  [Unknown]:  GB
Is CN=Alfresco Repository, OU=Unknown, O=Alfresco Software Ltd., L=Maidenhead, ST=UK, C=GB correct?
  [no]:  yes

Enter key password for <repo>
   (RETURN if same as keystore password): 
   
(ii) Generate a certificate request for the repository key

$ keytool -keystore ssl.keystore -alias repo -certreq -file repo.csr -storetype JCEKS -storepass <store password>

(iii) Alfresco CA signs the certificate request, creating a certificate that is valid for 365 days.

$ openssl x509 -CA ca.crt -CAkey ca.key -CAcreateserial -req -in repo.csr -out repo.crt -days 365
Signature ok
subject=/C=GB/ST=UK/L=Maidenhead/O=Alfresco Software Ltd./OU=Unknown/CN=Alfresco Repository
Getting CA Private Key
Enter pass phrase for ca.key:

(iv) Import the Alfresco CA key into the repository key store

$ keytool -import -alias AlfrescoCA -file ca.crt -keystore ssl.keystore -storetype JCEKS -storepass <store password>
Enter keystore password: 
Owner: CN=Alfresco CA, O=Alfresco Software Ltd., L=Maidenhead, ST=UK, C=GB
Issuer: CN=Alfresco CA, O=Alfresco Software Ltd., L=Maidenhead, ST=UK, C=GB
Serial number: 805ba6dc8f62f8b8
Valid from: Fri Aug 12 13:28:58 BST 2011 until: Mon Aug 09 13:28:58 BST 2021
Certificate fingerprints:
    MD5:  4B:45:94:2D:8E:98:E8:12:04:67:AD:AE:48:3C:F5:A0
    SHA1: 74:42:22:D0:52:AD:82:7A:FD:37:46:37:91:91:F4:77:89:3A:C9:A3
    Signature algorithm name: SHA1withRSA
    Version: 3

Extensions:

#1: ObjectId: 2.5.29.14 Criticality=false
SubjectKeyIdentifier [
KeyIdentifier [
0000: 08 42 40 DC FE 4A 50 87   05 2B 38 4D 92 70 8E 51  .B@..JP..+8M.p.Q
0010: 4E 38 71 D6                                        N8q.
]
]

#2: ObjectId: 2.5.29.19 Criticality=false
BasicConstraints:[
  CA:true
  PathLen:2147483647
]

#3: ObjectId: 2.5.29.35 Criticality=false
AuthorityKeyIdentifier [
KeyIdentifier [
0000: 08 42 40 DC FE 4A 50 87   05 2B 38 4D 92 70 8E 51  .B@..JP..+8M.p.Q
0010: 4E 38 71 D6                                        N8q.
]

[CN=Alfresco CA, O=Alfresco Software Ltd., L=Maidenhead, ST=UK, C=GB]
SerialNumber: [    805ba6dc 8f62f8b8]
]

Trust this certificate? [no]:  yes
Certificate was added to keystore

(v) Import the CA-signed repository certificate into the repository keystore

$ keytool -import -alias repo -file repo.crt -keystore ssl.keystore -storetype JCEKS -storepass <store password>
Enter keystore password: 
Certificate reply was installed in keystore

(vi) Convert the repository keystore to a pkcs12 keystore (for use in browsers such as Firefox). Give the pkcs12 key store the key store password 'alfresco'.

keytool -importkeystore -srckeystore ssl.keystore -srcstorepass <keystore password> -srcstoretype JCEKS -srcalias repo -srckeypass kT9X6oe68t -destkeystore firefox.p12 -deststoretype pkcs12 -deststorepass alfresco -destalias repo -destkeypass alfresco

(vi) Create a repository truststore containing the Alfresco CA certificate

keytool -import -alias AlfrescoCA -file ca.crt -keystore ssl.keystore -storetype JCEKS -storepass <store password>

(vii) Copy the keystore and truststore to the repository keystore location defined by the property 'dir.keystore'.
(viii) Update the SSL properties i.e. properties starting with the prefixes 'alfresco.encryption.ssl.keystore' and 'alfresco.encryption.ssl.truststore'.

Instructions for Generating a Certificate Authority (CA) Key and Certificate
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~

(i) Generate the CA private key

$ openssl genrsa -des3 -out ca.key 1024
Generating RSA private key, 1024 bit long modulus
……….++++++
..++++++
e is 65537 (0x10001)
Enter pass phrase for ca.key:
Verifying - Enter pass phrase for ca.key:

(ii) Generate the CA self-signed certificate

$ openssl req -new -x509 -days 3650 -key ca.key -out ca.crt
Enter pass phrase for ca.key:
You are about to be asked to enter information that will be incorporated
into your certificate request.
What you are about to enter is what is called a Distinguished Name or a DN.
There are quite a few fields but you can leave some blank
For some fields there will be a default value,
If you enter '.', the field will be left blank.
—–
Country Name (2 letter code) [AU]:GB
State or Province Name (full name) [Some-State]:UK
Locality Name (eg, city) []:Maidenhead
Organization Name (eg, company) [Internet Widgits Pty Ltd]:Alfresco Software Ltd.
Organizational Unit Name (eg, section) []:
Common Name (eg, YOUR name) []:Alfresco CA
Email Address []:

I can execute all of the steps without any errors except the last one, (vi) Create a repository truststore containing the Alfresco CA certificate.  I get this error: keytool error: java.lang.Exception: Certificate not imported, alias <AlfrescoCA> already exists

I now have a keystore file, but I don't believe it's complete and I also don't have a trust store file.

Couple of notes.  I can run 3.4d on this machine, using the same MySQL instance without any issues.  Also, I've had no success installing and running 4.0b either.

Any insight would be much appreciated!

Best,
Ben
7 REPLIES 7

mrogers
Star Contributor
Star Contributor
Last Night's build has a blocker bug.   Andy fixed it earlier today.

See ALF-11970

benswitzer
Champ in-the-making
Champ in-the-making
Thanks very much.

Funny, I saw that issue while searching.  Wasn't sure if it would appear right away in the nightly build.

Will report back.

benswitzer
Champ in-the-making
Champ in-the-making
Success!

Running default install right now.

Going to attempt upgrade from 3.4d shortly.

benswitzer
Champ in-the-making
Champ in-the-making
Smiley Sad  Upgrade was a no go.  Will start another thread for that issue.

norgan
Champ in-the-making
Champ in-the-making
Hi Ben,
there is a bug in the "CreateSSLKeystores.txt" I guess (for all others : Source : tomcat/webapps/alfresco/WEB-INF/classes/alfresco/keystore/CreateSSLKeystores.txt)

About step VI - I guess that is because you called the exact same line in step IV already.

I can execute all of the steps without any errors except the last one, (vi) Create a repository truststore containing the Alfresco CA certificate. I get this error: keytool error: java.lang.Exception: Certificate not imported, alias <AlfrescoCA> already exists

So I guess the name for the truststore has to be different . (I just named it ssl.truststore for my test and by now found the abovementioned folder seeing I prob am right)

Still, the bug still is there - so one question about this - how about deactivating SOLR ? I let you know how I proceed

benswitzer
Champ in-the-making
Champ in-the-making
Ahh, thanks Norgan.  Wasn't paying close enough attention.  This is good to know.

Anyway, the latest nightly build had corrected this issue and I've moved on.  Working on overcoming another issue.

biancama
Champ in-the-making
Champ in-the-making
Hi All,
if you want to use Community 4.0b there is a simple workaround. You have to override  dir.keystore with the key store included in alfresco war file.

sudo rm -rf /opt/alf_data/keystore
sudo cp -r /opt/apache-tomcat-6.0.35/webapps/alfresco/WEB-INF/classes/alfresco/keystore /opt/alf_data/

where /opt/alf_data/keystore is dir.keystore and /opt/apache-tomcat-6.0.35 is the installation folder of apache

Cheers
Massimo