AnsweredAssumed Answered

Alfresco wan't start in OSX 10.6 after PermGen Error

Question asked by frodelau on Feb 24, 2010
Latest reply on Feb 25, 2010 by frodelau
Hi,
I have tried several times to get Alfresco up and running, without luck so far :/

I have a 10.6 installation with MySQL and the latest availible CE of Alfresco installed using the OSX Installer. The installation works fine by itself. And the first startup of Alfresco is also promising. But after entering http://myhost:8080/alfresco and logging in, I get the famous PermGen error in the log and a blank page in my browser.

The startup script seems fine with regard to Java heap size.

Hope there are someone out there who knows a solution to this. Thanks for helping :)

Here is my log when trying to start Alfresco after the PermGen error:

Exception in thread "RMI RenewClean-[192.168.1.51:50502]" java.lang.OutOfMemoryError: PermGen space
Exception in thread "RMI RenewClean-[192.168.1.51:54591]" java.lang.OutOfMemoryError: PermGen space
Exception in thread "RMI RenewClean-[192.168.1.51:50505]" java.lang.OutOfMemoryError: PermGen space
Exception in thread "RMI RenewClean-[192.168.1.51:50503]" java.lang.OutOfMemoryError: PermGen space
Exception in thread "RMI RenewClean-[192.168.1.51:50504]" java.lang.OutOfMemoryError: PermGen space
Exception in thread "AWT-Shutdown" java.lang.OutOfMemoryError: PermGen space
Exception in thread "AWT-Shutdown" java.lang.OutOfMemoryError: PermGen space
Exception in thread "MessageDispatcher" java.lang.OutOfMemoryError: PermGen space
Exception in thread "AWT-Shutdown" java.lang.OutOfMemoryError: PermGen space
Exception in thread "RMI RenewClean-[192.168.1.51:50507]" java.lang.OutOfMemoryError: PermGen space
Feb 24, 2010 1:02:04 AM org.apache.coyote.http11.Http11Protocol init
SEVERE: Error initializing endpoint
java.net.BindException: Address already in use<null>:8080
   at org.apache.tomcat.util.net.JIoEndpoint.init(JIoEndpoint.java:502)
   at org.apache.coyote.http11.Http11Protocol.init(Http11Protocol.java:176)
   at org.apache.catalina.connector.Connector.initialize(Connector.java:1058)
   at org.apache.catalina.core.StandardService.initialize(StandardService.java:677)
   at org.apache.catalina.core.StandardServer.initialize(StandardServer.java:795)
   at org.apache.catalina.startup.Catalina.load(Catalina.java:530)
   at org.apache.catalina.startup.Catalina.load(Catalina.java:550)
   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.load(Bootstrap.java:260)
   at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:412)
Feb 24, 2010 1:02:04 AM org.apache.catalina.startup.Catalina load
SEVERE: Catalina.start
LifecycleException:  Protocol handler initialization failed: java.net.BindException: Address already in use<null>:8080
   at org.apache.catalina.connector.Connector.initialize(Connector.java:1060)
   at org.apache.catalina.core.StandardService.initialize(StandardService.java:677)
   at org.apache.catalina.core.StandardServer.initialize(StandardServer.java:795)
   at org.apache.catalina.startup.Catalina.load(Catalina.java:530)
   at org.apache.catalina.startup.Catalina.load(Catalina.java:550)
   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.load(Bootstrap.java:260)
   at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:412)
Feb 24, 2010 1:02:04 AM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 1239 ms
Feb 24, 2010 1:02:05 AM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Feb 24, 2010 1:02:05 AM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.18
Feb 24, 2010 1:02:07 AM 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.
Exception in thread "AWT-Shutdown" java.lang.OutOfMemoryError: PermGen space
01:02:18,945  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/repository.properties]
01:02:18,999  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/domain/transaction.properties]
01:02:18,999  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from URL [file:/Users/fsalfresco/Alfresco/tomcat/shared/classes/alfresco-global.properties]
01:02:19,229  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
Exception in thread "AWT-Shutdown" java.lang.OutOfMemoryError: PermGen space
Exception in thread "RMI TCP Connection(idle)" java.lang.OutOfMemoryError: PermGen space
Exception in thread "RMI TCP Connection(idle)" java.lang.OutOfMemoryError: PermGen space
01:03:51,811  ERROR [web.context.ContextLoader] Context initialization failed
org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'org.springframework.remoting.rmi.RmiServiceExporter' defined in class path resource [alfresco/emailserver/email-service-context.xml]: Invocation of init method failed; nested exception is java.rmi.server.ExportException: Port already in use: 50500; nested exception is:
   java.net.BindException: Address already in use
Caused by: java.rmi.server.ExportException: Port already in use: 50500; nested exception is:
   java.net.BindException: Address already in use
   at sun.rmi.transport.tcp.TCPTransport.listen(TCPTransport.java:310)
   at sun.rmi.transport.tcp.TCPTransport.exportObject(TCPTransport.java:218)
   at sun.rmi.transport.tcp.TCPEndpoint.exportObject(TCPEndpoint.java:393)
   at sun.rmi.transport.LiveRef.exportObject(LiveRef.java:129)
   at sun.rmi.server.UnicastServerRef.exportObject(UnicastServerRef.java:190)
   at sun.rmi.registry.RegistryImpl.setup(RegistryImpl.java:92)
   at sun.rmi.registry.RegistryImpl.<init>(RegistryImpl.java:78)
   at java.rmi.registry.LocateRegistry.createRegistry(LocateRegistry.java:186)
   at org.springframework.remoting.rmi.RmiServiceExporter.getRegistry(RmiServiceExporter.java:398)
   at org.springframework.remoting.rmi.RmiServiceExporter.getRegistry(RmiServiceExporter.java:370)
   at org.springframework.remoting.rmi.RmiServiceExporter.getRegistry(RmiServiceExporter.java:331)
   at org.springframework.remoting.rmi.RmiServiceExporter.prepare(RmiServiceExporter.java:263)
   at org.springframework.remoting.rmi.RmiServiceExporter.afterPropertiesSet(RmiServiceExporter.java:227)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1203)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1172)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:427)
   at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:249)
   at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:155)
   at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:246)
   at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:160)
   at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:291)
   at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:352)
   at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:246)
   at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:189)
   at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:49)
   at org.alfresco.web.app.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:69)
   at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3843)
   at org.apache.catalina.core.StandardContext.start(StandardContext.java:4342)
   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:525)
   at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:627)
   at org.apache.catalina.startup.HostConfig.deployDescriptors(HostConfig.java:553)
   at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:488)
   at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1149)
   at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:311)
   at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:117)
   at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1053)
   at org.apache.catalina.core.StandardHost.start(StandardHost.java:719)
   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:578)
   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:288)
   at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:413)
Caused by: java.net.BindException: Address already in use
   at java.net.PlainSocketImpl.socketBind(Native Method)
   at java.net.PlainSocketImpl.bind(PlainSocketImpl.java:365)
   at java.net.ServerSocket.bind(ServerSocket.java:319)
   at java.net.ServerSocket.<init>(ServerSocket.java:185)
   at java.net.ServerSocket.<init>(ServerSocket.java:97)
   at sun.rmi.transport.proxy.RMIDirectSocketFactory.createServerSocket(RMIDirectSocketFactory.java:27)
   at sun.rmi.transport.proxy.RMIMasterSocketFactory.createServerSocket(RMIMasterSocketFactory.java:333)
   at sun.rmi.transport.tcp.TCPEndpoint.newServerSocket(TCPEndpoint.java:649)
   at sun.rmi.transport.tcp.TCPTransport.listen(TCPTransport.java:299)
   … 49 more
Feb 24, 2010 1:03:51 AM org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
Feb 24, 2010 1:03:51 AM org.apache.catalina.core.StandardContext start
SEVERE: Context [/alfresco] startup failed due to previous errors
log4j:ERROR LogMananger.repositorySelector was null likely due to error in class reloading, using NOPLoggerRepository.
[Deprecated] Xalan: org.apache.xml.resolver.Catalog
Feb 24, 2010 1:03:54 AM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive mobile.war
01:03:56,191  INFO  [alfresco.config.JBossEnabledWebApplicationContext] Refreshing org.alfresco.config.JBossEnabledWebApplicationContext@7e5dfc79: display name [Root WebApplicationContext]; startup date [Wed Feb 24 01:03:56 CET 2010]; root of context hierarchy
01:03:56,912  INFO  [alfresco.config.JBossEnabledWebApplicationContext] Bean factory for application context [org.alfresco.config.JBossEnabledWebApplicationContext@7e5dfc79]: org.springframework.beans.factory.support.DefaultListableBeanFactory@43a63a1c
01:03:59,055  INFO  [web.scripts.DeclarativeRegistry] Registered 22 Web Scripts (+0 failed), 24 URLs
01:03:59,100  INFO  [web.scripts.AbstractRuntimeContainer] Initialised Presentation Web Script Container (in 507.46ms)
01:03:59,436  INFO  [web.scripts.DeclarativeRegistry] Registered 40 Web Scripts (+0 failed), 42 URLs
01:03:59,482  INFO  [web.scripts.AbstractRuntimeContainer] Initialised WebFramework Web Script Container (in 374.15298ms)
01:03:59,580  INFO  [web.site.FrameworkHelper] Successfully Initialized Web Framework
Feb 24, 2010 1:03:59 AM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive share.war
01:04:05,437  INFO  [web.site.FrameworkHelper] Successfully Initialized Web Framework
Feb 24, 2010 1:04:06 AM org.apache.coyote.http11.Http11Protocol start
SEVERE: Error starting endpoint
java.net.BindException: Address already in use<null>:8080
   at org.apache.tomcat.util.net.JIoEndpoint.init(JIoEndpoint.java:502)
   at org.apache.tomcat.util.net.JIoEndpoint.start(JIoEndpoint.java:519)
   at org.apache.coyote.http11.Http11Protocol.start(Http11Protocol.java:203)
   at org.apache.catalina.connector.Connector.start(Connector.java:1131)
   at org.apache.catalina.core.StandardService.start(StandardService.java:531)
   at org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
   at org.apache.catalina.startup.Catalina.start(Catalina.java:578)
   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:288)
   at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:413)
Feb 24, 2010 1:04:06 AM org.apache.catalina.startup.Catalina start
SEVERE: Catalina.start:
LifecycleException:  service.getName(): "Catalina";  Protocol handler start failed: java.net.BindException: Address already in use<null>:8080
   at org.apache.catalina.connector.Connector.start(Connector.java:1138)
   at org.apache.catalina.core.StandardService.start(StandardService.java:531)
   at org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
   at org.apache.catalina.startup.Catalina.start(Catalina.java:578)
   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:288)
   at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:413)
Feb 24, 2010 1:04:06 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 121169 ms
Feb 24, 2010 1:04:06 AM org.apache.catalina.core.StandardServer await
SEVERE: StandardServer.await: create[8005]:
java.net.BindException: Address already in use
   at java.net.PlainSocketImpl.socketBind(Native Method)
   at java.net.PlainSocketImpl.bind(PlainSocketImpl.java:365)
   at java.net.ServerSocket.bind(ServerSocket.java:319)
   at java.net.ServerSocket.<init>(ServerSocket.java:185)
   at org.apache.catalina.core.StandardServer.await(StandardServer.java:373)
   at org.apache.catalina.startup.Catalina.await(Catalina.java:642)
   at org.apache.catalina.startup.Catalina.start(Catalina.java:602)
   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:288)
   at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:413)
Feb 24, 2010 1:04:06 AM org.apache.coyote.http11.Http11Protocol pause
INFO: Pausing Coyote HTTP/1.1 on http-8080
Feb 24, 2010 1:04:07 AM org.apache.catalina.core.StandardService stop
INFO: Stopping service Catalina
01:04:07,200  INFO  [alfresco.config.JBossEnabledWebApplicationContext] Closing org.alfresco.config.JBossEnabledWebApplicationContext@7e5dfc79: display name [Root WebApplicationContext]; startup date [Wed Feb 24 01:03:56 CET 2010]; root of context hierarchy
Feb 24, 2010 1:04:08 AM org.apache.catalina.connector.MapperListener destroy
WARNING: Error unregistering MBeanServerDelegate
java.lang.NullPointerException
   at org.apache.catalina.connector.MapperListener.destroy(MapperListener.java:165)
   at org.apache.catalina.connector.Connector.stop(Connector.java:1179)
   at org.apache.catalina.core.StandardService.stop(StandardService.java:593)
   at org.apache.catalina.core.StandardServer.stop(StandardServer.java:744)
   at org.apache.catalina.startup.Catalina.stop(Catalina.java:628)
   at org.apache.catalina.startup.Catalina$CatalinaShutdownHook.run(Catalina.java:671)
Feb 24, 2010 1:04:08 AM org.apache.coyote.http11.Http11Protocol destroy
INFO: Stopping Coyote HTTP/1.1 on http-8080
Exception in thread "AWT-Shutdown" java.lang.OutOfMemoryError: PermGen space
Exception in thread "DefaultScheduler_Worker-10" java.lang.OutOfMemoryError: PermGen space
Exception in thread "AWT-Shutdown" java.lang.OutOfMemoryError: PermGen space
Exception in thread "AWT-Shutdown" java.lang.OutOfMemoryError: PermGen space

Outcomes