Error: One or more listeners failed to start

[preyes]

Buenas, estoy intentando levantar manualmente un servidor nuevo con Alfresco 6.2 pero no inicia.

Desde la web "http://192.168.33.14:8080/"  me devuelve:

Cannot find Alfresco Repository on this server. (Does this application have access to alfresco-global.properties? Does this application have cross-context permissions?

El archivo /opt/tomcat/logs/localhost.2020-07-01.log me registro esto:

01-Jul-2020 17:37:01.103 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log Java Home: /usr/lib/jvm/java-11-openjdk-11.0.7.10-4.el7_8.x86_64
01-Jul-2020 17:37:01.103 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Version: 11.0.7+10-LTS
01-Jul-2020 17:37:01.104 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log Vededor JVM: Oracle Corporation
01-Jul-2020 17:37:01.104 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_BASE: /opt/tomcat
01-Jul-2020 17:37:01.105 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_HOME: /opt/tomcat
01-Jul-2020 17:37:01.117 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.lang=ALL-UNNAMED
01-Jul-2020 17:37:01.117 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.io=ALL-UNNAMED
01-Jul-2020 17:37:01.118 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.rmi/sun.rmi.transport=ALL-UNNAMED
01-Jul-2020 17:37:01.118 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.config.file=/opt/tomcat/conf/logging.properties
01-Jul-2020 17:37:01.118 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager
01-Jul-2020 17:37:01.118 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Xms512M
01-Jul-2020 17:37:01.119 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Xmx1024M
01-Jul-2020 17:37:01.119 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djdk.tls.ephemeralDHKeySize=2048
01-Jul-2020 17:37:01.119 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.protocol.handler.pkgs=org.apache.catalina.webresources
01-Jul-2020 17:37:01.120 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dorg.apache.catalina.security.SecurityListener.UMASK=0027
01-Jul-2020 17:37:01.120 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dignore.endorsed.dirs=
01-Jul-2020 17:37:01.121 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.base=/opt/tomcat
01-Jul-2020 17:37:01.121 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.home=/opt/tomcat
01-Jul-2020 17:37:01.122 INFORMACIÓN [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.io.tmpdir=/opt/tomcat/temp
01-Jul-2020 17:37:01.122 INFORMACIÓN [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent La biblioteca nativa de Apache Tomcat basada en ARP que permite un rendimiento óptimo en entornos de desarrollo no ha sido hallada en java.library.path: [/usr/java/packages/lib:/usr/lib64:/lib64:/lib:/usr/lib]
01-Jul-2020 17:37:01.375 INFORMACIÓN [main] org.apache.coyote.AbstractProtocol.init Inicializando el manejador de protocolo ["https-jsse-nio-8443"]

01-Jul-2020 17:37:01.933 ADVERTENCIA [main] org.apache.tomcat.util.net.SSLUtilBase.<init> The JSSE TLS 1.3 implementation does not support authentication after the initial handshake and is therefore incompatible with optional client authentication
01-Jul-2020 17:37:02.585 INFORMACIÓN [main] org.apache.tomcat.util.net.NioSelectorPool.getSharedSelector Using a shared selector for servlet write/read
01-Jul-2020 17:37:02.673 INFORMACIÓN [main] org.apache.coyote.AbstractProtocol.init Inicializando el manejador de protocolo ["http-nio-8080"]

01-Jul-2020 17:37:02.678 INFORMACIÓN [main] org.apache.tomcat.util.net.NioSelectorPool.getSharedSelector Using a shared selector for servlet write/read
01-Jul-2020 17:37:02.697 INFORMACIÓN [main] org.apache.coyote.AbstractProtocol.init Inicializando el manejador de protocolo ["ajp-nio-8009"]

01-Jul-2020 17:37:02.700 INFORMACIÓN [main] org.apache.tomcat.util.net.NioSelectorPool.getSharedSelector Using a shared selector for servlet write/read
01-Jul-2020 17:37:02.715 INFORMACIÓN [main] org.apache.catalina.startup.Catalina.load Initialization processed in 3458 ms
01-Jul-2020 17:37:02.939 INFORMACIÓN [main] org.apache.catalina.core.StandardService.startInternal Arrancando servicio [Catalina]
01-Jul-2020 17:37:02.939 INFORMACIÓN [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet Engine: Apache Tomcat/8.5.46
01-Jul-2020 17:37:02.989 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDescriptor Desplieque del descriptor de configuración [/opt/tomcat/conf/Catalina/localhost/alfresco.xml]
01-Jul-2020 17:37:37.761 GRAVE [localhost-startStop-1] org.apache.catalina.core.StandardContext.startInternal One or more listeners failed to start. Full details will be found in the appropriate container log file
01-Jul-2020 17:37:37.825 GRAVE [localhost-startStop-1] org.apache.catalina.core.StandardContext.startInternal Falló en arranque del Contexto [/alfresco] debido a errores previos
01-Jul-2020 17:37:38.057 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDescriptor Deployment of deployment descriptor [/opt/tomcat/conf/Catalina/localhost/alfresco.xml] has finished in [35.067] ms
01-Jul-2020 17:37:38.058 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDescriptor Desplieque del descriptor de configuración [/opt/tomcat/conf/Catalina/localhost/share.xml]
01-Jul-2020 17:37:43.282 INFORMACIÓN [localhost-startStop-1] org.apache.jasper.servlet.TldScanner.scanJars Al menos un JAR, que se ha explorado buscando TLDs, aún no contenía TLDs. Activar historial de depuración para este historiador para una completa lista de los JARs que fueron explorados y de los que nos se halló TLDs. Saltarse JARs no necesarios durante la exploración puede dar lugar a una mejora de tiempo significativa en el arranque y compilación de JSP .
01-Jul-2020 17:38:07.842 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDescriptor Deployment of deployment descriptor [/opt/tomcat/conf/Catalina/localhost/share.xml] has finished in [29.783] ms
01-Jul-2020 17:38:07.860 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployWAR Despliegue del archivo [/opt/tomcat/webapps/ROOT.war] de la aplicación web
01-Jul-2020 17:38:07.866 ADVERTENCIA [localhost-startStop-1] org.apache.catalina.startup.SetContextPropertiesRule.begin [SetContextPropertiesRule]{Context} Setting property 'debug' to '100' did not find a matching property.
01-Jul-2020 17:38:08.059 INFORMACIÓN [localhost-startStop-1] org.apache.jasper.servlet.TldScanner.scanJars Al menos un JAR, que se ha explorado buscando TLDs, aún no contenía TLDs. Activar historial de depuración para este historiador para una completa lista de los JARs que fueron explorados y de los que nos se halló TLDs. Saltarse JARs no necesarios durante la exploración puede dar lugar a una mejora de tiempo significativa en el arranque y compilación de JSP .
01-Jul-2020 17:38:08.063 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployWAR Deployment of web application archive [/opt/tomcat/webapps/ROOT.war] has finished in [202] ms
01-Jul-2020 17:38:08.064 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployWAR Despliegue del archivo [/opt/tomcat/webapps/_vti_bin.war] de la aplicación web
01-Jul-2020 17:38:08.366 INFORMACIÓN [localhost-startStop-1] org.apache.jasper.servlet.TldScanner.scanJars Al menos un JAR, que se ha explorado buscando TLDs, aún no contenía TLDs. Activar historial de depuración para este historiador para una completa lista de los JARs que fueron explorados y de los que nos se halló TLDs. Saltarse JARs no necesarios durante la exploración puede dar lugar a una mejora de tiempo significativa en el arranque y compilación de JSP .
01-Jul-2020 17:38:08.372 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployWAR Deployment of web application archive [/opt/tomcat/webapps/_vti_bin.war] has finished in [308] ms
01-Jul-2020 17:38:08.385 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Desplegando el directorio [/opt/tomcat/webapps/docs] de la aplicación web
01-Jul-2020 17:38:08.557 INFORMACIÓN [localhost-startStop-1] org.apache.jasper.servlet.TldScanner.scanJars Al menos un JAR, que se ha explorado buscando TLDs, aún no contenía TLDs. Activar historial de depuración para este historiador para una completa lista de los JARs que fueron explorados y de los que nos se halló TLDs. Saltarse JARs no necesarios durante la exploración puede dar lugar a una mejora de tiempo significativa en el arranque y compilación de JSP .
01-Jul-2020 17:38:08.562 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/docs] has finished in [176] ms
01-Jul-2020 17:38:08.562 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Desplegando el directorio [/opt/tomcat/webapps/examples] de la aplicación web
01-Jul-2020 17:38:09.007 INFORMACIÓN [localhost-startStop-1] org.apache.jasper.servlet.TldScanner.scanJars Al menos un JAR, que se ha explorado buscando TLDs, aún no contenía TLDs. Activar historial de depuración para este historiador para una completa lista de los JARs que fueron explorados y de los que nos se halló TLDs. Saltarse JARs no necesarios durante la exploración puede dar lugar a una mejora de tiempo significativa en el arranque y compilación de JSP .
01-Jul-2020 17:38:09.114 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/examples] has finished in [552] ms
01-Jul-2020 17:38:09.114 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Desplegando el directorio [/opt/tomcat/webapps/host-manager] de la aplicación web
01-Jul-2020 17:38:09.293 INFORMACIÓN [localhost-startStop-1] org.apache.jasper.servlet.TldScanner.scanJars Al menos un JAR, que se ha explorado buscando TLDs, aún no contenía TLDs. Activar historial de depuración para este historiador para una completa lista de los JARs que fueron explorados y de los que nos se halló TLDs. Saltarse JARs no necesarios durante la exploración puede dar lugar a una mejora de tiempo significativa en el arranque y compilación de JSP .
01-Jul-2020 17:38:09.315 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/host-manager] has finished in [200] ms
01-Jul-2020 17:38:09.315 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Desplegando el directorio [/opt/tomcat/webapps/manager] de la aplicación web
01-Jul-2020 17:38:09.519 INFORMACIÓN [localhost-startStop-1] org.apache.jasper.servlet.TldScanner.scanJars Al menos un JAR, que se ha explorado buscando TLDs, aún no contenía TLDs. Activar historial de depuración para este historiador para una completa lista de los JARs que fueron explorados y de los que nos se halló TLDs. Saltarse JARs no necesarios durante la exploración puede dar lugar a una mejora de tiempo significativa en el arranque y compilación de JSP .
01-Jul-2020 17:38:09.523 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/manager] has finished in [208] ms
01-Jul-2020 17:38:09.547 INFORMACIÓN [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["https-jsse-nio-8443"]
01-Jul-2020 17:38:09.639 INFORMACIÓN [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8080"]
01-Jul-2020 17:38:09.648 INFORMACIÓN [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["ajp-nio-8009"]
01-Jul-2020 17:38:09.668 INFORMACIÓN [main] org.apache.catalina.startup.Catalina.start Server startup in 66952 ms

Y el archivo /opt/tomcat/logs/localhost.2020-07-01.log registro esto:

01-Jul-2020 17:36:58.407 INFORMACIÓN [localhost-startStop-2] org.apache.catalina.core.ApplicationContext.log SessionListener: contextDestroyed()
01-Jul-2020 17:36:58.408 INFORMACIÓN [localhost-startStop-2] org.apache.catalina.core.ApplicationContext.log ContextListener: contextDestroyed()
01-Jul-2020 17:37:42.839 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log No Spring WebApplicationInitializer types detected on classpath
01-Jul-2020 17:37:43.482 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log Initializing Spring root WebApplicationContext
01-Jul-2020 17:38:07.671 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: loaded (conf ok)
01-Jul-2020 17:38:07.762 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log Initializing Spring DispatcherServlet 'Spring Surf Dispatcher Servlet'
01-Jul-2020 17:38:09.093 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log ContextListener: contextInitialized()
01-Jul-2020 17:38:09.093 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log SessionListener: contextInitialized()
01-Jul-2020 17:38:09.095 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log ContextListener: attributeAdded('StockTicker', 'async.Stockticker@46bd73b7')
01-Jul-2020 17:54:27.932 INFORMACIÓN [localhost-startStop-2] org.apache.catalina.core.ApplicationContext.log SessionListener: contextDestroyed()
01-Jul-2020 17:54:27.932 INFORMACIÓN [localhost-startStop-2] org.apache.catalina.core.ApplicationContext.log ContextListener: contextDestroyed()
01-Jul-2020 17:54:28.033 INFORMACIÓN [localhost-startStop-2] org.apache.catalina.core.ApplicationContext.log Destroying Spring FrameworkServlet 'Spring Surf Dispatcher Servlet'
01-Jul-2020 17:54:28.083 INFORMACIÓN [localhost-startStop-2] org.apache.catalina.core.ApplicationContext.log org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: destroy called
01-Jul-2020 17:54:28.092 INFORMACIÓN [localhost-startStop-2] org.apache.catalina.core.ApplicationContext.log Closing Spring root WebApplicationContext
01-Jul-2020 17:56:08.241 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log No Spring WebApplicationInitializer types detected on classpath
01-Jul-2020 17:56:08.830 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log Initializing Spring root WebApplicationContext
01-Jul-2020 17:56:35.546 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: loaded (conf ok)
01-Jul-2020 17:56:35.652 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log Initializing Spring DispatcherServlet 'Spring Surf Dispatcher Servlet'
01-Jul-2020 17:56:37.269 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log ContextListener: contextInitialized()
01-Jul-2020 17:56:37.269 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log SessionListener: contextInitialized()
01-Jul-2020 17:56:37.276 INFORMACIÓN [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log ContextListener: attributeAdded('StockTicker', 'async.Stockticker@5e2ff048')

Tuve varios problemas que fui solucionando para iniciarlo, pero aca no se qué puede estar pasando.

Como dato agrego que el acceso web http://192.168.33.14:8080/share/page/ cargó correctamente.

Muchas gracias, saludos!

Ing. Pablo Reyes

[angelborroy]

La aplicación "alfresco.war" no está siendo arrancada.

Échale un vistazo a este documento para verificar si has olvidado realizar algún paso:

https://github.com/OrderOfTheBee/beecon/blob/gh-pages/2017/assets/files/EF09/EF09-Installing-Alfresc...

[preyes]

Gracias, voy a probar con ese instructivo a ver si puedo solucionarlo. Igualmente es de otra version y por lo que veo cambia algunas cosas.

Es necesario instalar el share y alfresco en carpetas distintas? Eso no lo habia visto asi en la documentacion.

[angelborroy]

No, no es necesario.

Las instrucciones sirven también para la versión que estás instalando.

[preyes]

Gracias, revisando el tutorial tenia todo correcto. Al final encontré mas informacion viendo los logs del SO en /var/log/messages.

En principio fallaba el servicio de logueo, porque tenia configurado en el archivo log4j.properties la variable:

"log4j.appender.File.File=alfresco.log"

Eso me tiraba un error de permisos y no lo creaba, asi que lo cambie por:

"log4j.appender.File.File=/var/log/alfresco/alfresco.log"

Igualmente puede iniciar el sistema con ese error.

Al final tenia que ver con la configuracion de las keystores. El SSLGenerator no me estaba creando todos los archivos que necesitaba, por la forma en que lo estaba ejecutando. Tenia que ejecutarlo con los siguientes parametros:

cd {{ alfresco_tomcat_dir }}/SSLGenerator/ssl-tool; ./run.sh -keystorepass "xxxxxxxx" -truststorepass "xxxxxxxx" -alfrescoformat "classic"

Hay alguna forma de configurar el destino de los archivos de logs antes de iniciar alfresco por primera vez? O sea, antes de que se despliegue el alfresco.war.

Porque ahora lo que hago es iniciarlo, y cuando se crea el archivo, modifico la linea correspondiente, luego reinicio alfresco y ahi recien crea el log.

Consulto esto porque lo estoy automatizando con Ansible y queda medio engorroso hacerlo de esta manera.

Saludos

[angelborroy · Accepted solution]

Podrías intentarlo con algún otro fichero de configuración de Log4j que no esté dentro de alfresco.war

$TOMCAT_HOME/shared/classes/alfresco/extension/dev-log4j.properties

https://docs.alfresco.com/6.0/concepts/dev-extensions-modules-module-log4j.html

O modificar el alfresco.war antes de desplegarlo.

[preyes]

Lo hice modificando los war de la siguiente forma:

Primero creo el archivo alfresco/WEB-INF/classes/log4j.properties con todas las opciones tal cual lo necesito.

Despues ejecuto

jar -uf alfresco.war -C alfresco WEB-INF/classes/log4j.properties;

Y al final elimino la plantilla:

rm -rf alfresco/

Tengo otros errores, pero lo cargo en otra consulta porque es otro tema

Muchas gracias!