Charles, I don't believe the spring errors shown in the log are affecting the run, because when I turn up logging on a previous installation that runs fine, I see those same exceptions. Also, I am running on a windows machine, but have sufficient permissions to open ports below 1024 (again, a previous 3.0-beta2 installation runs fine)
With regards to your recommendation to run a beta5 snapshot - I have installed the latest snapshot build and have gotten it to run a bit longer before dying by fixing some configuration settings - but it still dies after a second or two. Below is the last bit of the log, it seems to start dying when calling init on bean 'mailprocessor' - the init() throws an exception (not shown) then everything starts shutting down. Relevant line: DEBUG 08:31:53,616 | org.springframework.context.annotation.CommonAnnotationBeanPostProcessor | Invoking init method on bean 'mailprocessor': public void org.apache.james.mailetcontainer.lib.AbstractStateCompositeProcessor.init() throws java.lang.Exception I've checked my <processor> elements in mailetcontainer.conf, but I don't have anything crazy in there, pretty much exactly the same things as in a working (beta2) configuration, with mostly default configurations as well. If necessary I can include that configuration. Thanks, Kevin End of james-server.log : DEBUG 08:31:53,600 | org.springframework.beans.factory.support.DefaultListableBeanFactory | Finished creating instance of bean 'usersrepository' DEBUG 08:31:53,600 | org.springframework.beans.factory.annotation.InjectionMetadata | Processing injected method of bean 'mailetcontext': ResourceElement for public void org.apache.james.mailetcontainer.impl.JamesMailetContext.setDomainList(org.apache.james.domainlist.api.DomainList) DEBUG 08:31:53,600 | org.springframework.beans.factory.support.DefaultListableBeanFactory | Creating shared instance of singleton bean 'domainlist' DEBUG 08:31:53,600 | org.springframework.beans.factory.support.DefaultListableBeanFactory | Creating instance of bean 'domainlist' DEBUG 08:31:53,600 | org.springframework.beans.factory.annotation.InjectionMetadata | Found injected element on class [org.apache.james.domainlist.xml.XMLDomainList]: ResourceElement for public void org.apache.james.domainlist.lib.AbstractDomainList.setDNSService(org.apache.james.dnsservice.api.DNSService) DEBUG 08:31:53,600 | org.springframework.beans.factory.support.DefaultListableBeanFactory | Eagerly caching bean 'domainlist' to allow for resolving potential circular references DEBUG 08:31:53,600 | org.springframework.beans.factory.annotation.InjectionMetadata | Processing injected method of bean 'domainlist': ResourceElement for public void org.apache.james.domainlist.lib.AbstractDomainList.setDNSService(org.apache.james.dnsservice.api.DNSService) DEBUG 08:31:53,600 | org.springframework.beans.factory.support.DefaultListableBeanFactory | Returning cached instance of singleton bean 'dnsservice' INFO 08:31:53,600 | james.domainlist | Set autodetect to: true INFO 08:31:53,600 | james.domainlist | Set autodetectIP to: true DEBUG 08:31:53,600 | org.springframework.beans.factory.support.DefaultListableBeanFactory | Finished creating instance of bean 'domainlist' DEBUG 08:31:53,616 | org.springframework.beans.factory.support.DefaultListableBeanFactory | Finished creating instance of bean 'mailetcontext' DEBUG 08:31:53,616 | org.springframework.context.annotation.CommonAnnotationBeanPostProcessor | Invoking init method on bean 'mailprocessor': public void org.apache.james.mailetcontainer.lib.AbstractStateCompositeProcessor.init() throws java.lang.Exception DEBUG 08:31:53,616 | org.springframework.beans.factory.support.DefaultListableBeanFactory | Retrieved dependent beans for bean 'mailprocessor': [mailetcontext] DEBUG 08:31:53,616 | org.springframework.beans.factory.support.DefaultListableBeanFactory | Retrieved dependent beans for bean 'mailetcontext': [mailprocessor] INFO 08:31:53,631 | org.springframework.beans.factory.support.DefaultListableBeanFactory | Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@4693f9: defining beans [org.springframework.context.annotation.CommonAnnotationBeanPostProcessor#0,org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor#0,org.apache.james.container.spring.lifecycle.LogEnabledBeanPostProcessor#0,logprovider,org.apache.james.container.spring.bean.factorypostprocessor.ConfigurationBeanFactoryPostProcessor#0,org.apache.james.container.spring.lifecycle.ConfigurableBeanPostProcessor#0,configurationprovider,mailrepositorystore,mailqueuefactory,broker,amqConnectionFactory,blobTransferPolicy,jmsConnectionFactory,jmsTransactionManager,mailprocessor,mailetcontext,mailspooler,mailetloader,matcherloader,agent,producerTemplate,consumerTemplate,jamesCamelContext:beanPostProcessor,jamesCamelContext,org.apache.james.container.spring.bean.factorypostprocessor.FileSystemBeanFactoryPostProcessor#0,filesystem,dnsservice,fetchmail,protocolhandlerloader,smtpserver,lmtpserver,pop3server,imapserver,imapProcessor,imapDecoder,imapDecoderFactory,imapEncoder,imapEncoderFactory,org.apache.james.container.spring.bean.factorypostprocessor.MailboxConfigurationBeanFactoryPostProcessor#0,locker,jvm-locker,authenticator,lazyIndex,luceneIndex,ramDirectory,org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor#1,jpa-mailboxmanager,jpa-subscriptionManager,jpa-sessionMapperFactory,jpa-uidProvider,jpa-modSeqProvider,org.springframework.beans.factory.config.PropertyPlaceholderConfigurer#0,datasource,entityManagerFactory,vendorAdapter,org.springframework.beans.factory.config.PropertyPlaceholderConfigurer#1,maildir-mailboxmanager,maildir-subscriptionManager,maildir-sessionMapperFactory,maildirStore,memory-mailboxmanager,memory-subscriptionManager,memory-sessionMapperFactory,mailboxcopier,aclResolver,groupMembershipResolver,james23importer,org.springframework.beans.factory.config.PropertyPlaceholderConfigurer#2,org.springframework.beans.factory.config.PropertyPlaceholderConfigurer#3,mbeanserver,serverConnector,registry,restrictedRmiSocketFactory,exporter,usersrepositorymanagement,recipientrewritetablemanagement,domainlistmanagement,mailboxmanagermanagement,mailboxcopiermanagement,james23importermanagement,usersrepository,recipientrewritetable,domainlist,usersrepository23]; root of factory hierarchy DEBUG 08:31:53,631 | org.springframework.context.annotation.CommonAnnotationBeanPostProcessor | Invoking destroy method on bean 'mailqueuefactory': public void org.apache.james.queue.library.AbstractMailQueueFactory.destroy() DEBUG 08:31:53,631 | org.springframework.beans.factory.support.DisposableBeanAdapter | Invoking destroy() on bean with name 'jmsConnectionFactory' DEBUG 08:31:53,631 | org.springframework.beans.factory.support.DefaultListableBeanFactory | Retrieved dependent beans for bean '(inner bean)#3': [amqConnectionFactory] DEBUG 08:31:53,631 | org.springframework.context.annotation.CommonAnnotationBeanPostProcessor | Invoking destroy method on bean 'broker': public void org.apache.activemq.xbean.XBeanBrokerService.destroy() throws java.lang.Exception INFO 08:31:53,631 | org.apache.activemq.broker.BrokerService | ActiveMQ Message Broker (james, ID:direct-49811-1331908313131-0:1) is shutting down INFO 08:31:55,397 | org.apache.activemq.broker.TransportConnector | Connector tcp://localhost:0 Stopped INFO 08:31:55,397 | org.apache.activemq.store.kahadb.plist.PListStore | PListStore:..\var\store\activemq\brokers\james\tmp_storage stopped DEBUG 08:31:55,397 | org.apache.activemq.store.amq.AMQPersistenceAdapter | Waitng for checkpoint to complete. DEBUG 08:31:55,397 | org.apache.activemq.store.amq.AMQPersistenceAdapter | Checkpoint started. DEBUG 08:31:55,397 | org.apache.activemq.store.amq.AMQPersistenceAdapter | Checkpoint done. DEBUG 08:31:55,413 | org.apache.activemq.store.amq.AMQPersistenceAdapter | Journal close INFO 08:31:55,428 | org.apache.activemq.broker.BrokerService | ActiveMQ JMS Message Broker (james, ID:direct-49811-1331908313131-0:1) stopped DEBUG 08:31:55,428 | org.springframework.context.annotation.CommonAnnotationBeanPostProcessor | Invoking destroy method on bean 'broker': public void org.apache.activemq.broker.BrokerService.stop() throws java.lang.Exception DEBUG 08:31:55,428 | org.springframework.beans.factory.support.DefaultListableBeanFactory | Retrieved dependent beans for bean '(inner bean)': [broker] DEBUG 08:31:55,428 | org.springframework.beans.factory.support.DefaultListableBeanFactory | Retrieved dependent beans for bean 'amq:policyEntry#1cb7a1': [(inner bean)] DEBUG 08:31:55,428 | org.springframework.beans.factory.support.DisposableBeanAdapter | Invoking destroy() on bean with name 'jamesCamelContext' INFO 08:31:55,428 | org.apache.camel.impl.DefaultCamelContext | Apache Camel 2.5.0 (CamelContext:jamesCamelContext) is shutting down INFO 08:31:55,428 | org.apache.camel.impl.DefaultShutdownStrategy | Starting to graceful shutdown 0 routes (timeout 300 seconds) DEBUG 08:31:55,428 | org.apache.camel.impl.DefaultExecutorServiceStrategy | Created new single thread pool for source: org.apache.camel.impl.DefaultShutdownStrategy@935741 with name: ShutdownTask. -> java.util.concurrent.Executors$FinalizableDelegatedExecutorService@1458657 DEBUG 08:31:55,428 | org.apache.camel.impl.DefaultShutdownStrategy | There are 0 routes to shutdown INFO 08:31:55,444 | org.apache.camel.impl.DefaultShutdownStrategy | Graceful shutdown of 0 routes completed in 0 seconds INFO 08:31:55,444 | org.apache.camel.impl.DefaultCamelContext | Uptime: not started INFO 08:31:55,444 | org.apache.camel.impl.DefaultCamelContext | Apache Camel 2.5.0 (CamelContext: jamesCamelContext) is shutdown in 0.016 seconds -----Original Message----- From: Eric Charles [mailto:[email protected]] Sent: Friday, March 16, 2012 5:08 AM To: James Users List Subject: Re: James quits shortly after startup for unknown reason Hi, We defined the xsd url being classpath:... to avoid failure on startup if firewall block access to the Internet. But it seems tuning the log levels to DEBUG gives an exception... if this is the case, we need to tackle this with low priority, as it still starts? As you said, it continues to start, and after it fails/shutdowns... really no exception or error/warn messages to see in the log? Do you run with root privileges to start sockets below 1024? There may be problems with snapshots begin of March, any chance you give a try with a recent one from https://repository.apache.org/content/repositories/snapshots/org/apache/james/apache-james/3.0-beta5-SNAPSHOT/ ? Thx, Eric On 15/03/12 16:16, Dion, Kevin wrote: > I am having an issue with the beta4 snapshot build of james, specifically > apache-james-3.0-beta4-20120302.131953-590. It starts up, and then quits > without throwing an exception. I turned up logging on everything in the > log4j.properties, and I saw some early Spring errors along the lines of: > > DEBUG 08:54:55,482 | > org.springframework.beans.factory.xml.ResourceEntityResolver | Could not > resolve XML entity > [classpath:org/springframework/beans/factory/xml/spring-beans-3.0.xsd] > against system root URL > java.net.MalformedURLException: unknown protocol: classpath > ...stack trace... > > But it appears to continue from there for a while (I get several of the > MalformedURLExceptions), then end for no apparent reason later. I have > included below the last several dozen lines of the log file. > > Any assistance would be appreciated > > Thanks, > Kevin > > Here is the last bit of the james-server.log (can add more if necessary): > > DEBUG 08:54:57,560 | > org.springframework.beans.factory.support.DefaultListableBeanFactory | > Finished creating instance of bean 'datasource' > DEBUG 08:54:57,560 | > org.springframework.beans.factory.support.DefaultListableBeanFactory | > Creating shared instance of singleton bean 'vendorAdapter' > DEBUG 08:54:57,560 | > org.springframework.beans.factory.support.DefaultListableBeanFactory | > Creating instance of bean 'vendorAdapter' > DEBUG 08:54:57,576 | > org.springframework.beans.factory.support.DefaultListableBeanFactory | > Eagerly caching bean 'vendorAdapter' to allow for resolving potential > circular references > DEBUG 08:54:57,576 | org.springframework.core.env.StandardEnvironment | > Initializing new StandardEnvironment > DEBUG 08:54:57,576 | org.springframework.core.env.StandardEnvironment | > Adding [systemProperties] PropertySource with lowest search precedence > DEBUG 08:54:57,576 | org.springframework.core.env.StandardEnvironment | > Adding [systemEnvironment] PropertySource with lowest search precedence > DEBUG 08:54:57,576 | org.springframework.core.env.StandardEnvironment | > Initialized StandardEnvironment with PropertySources > [systemProperties,systemEnvironment] > DEBUG 08:54:57,576 | org.springframework.core.env.StandardEnvironment | > Initializing new StandardEnvironment > DEBUG 08:54:57,576 | org.springframework.core.env.StandardEnvironment | > Adding [systemProperties] PropertySource with lowest search precedence > DEBUG 08:54:57,576 | org.springframework.core.env.StandardEnvironment | > Adding [systemEnvironment] PropertySource with lowest search precedence > DEBUG 08:54:57,576 | org.springframework.core.env.StandardEnvironment | > Initialized StandardEnvironment with PropertySources > [systemProperties,systemEnvironment] > DEBUG 08:54:57,576 | org.springframework.core.env.StandardEnvironment | > Initializing new StandardEnvironment > DEBUG 08:54:57,576 | org.springframework.core.env.StandardEnvironment | > Adding [systemProperties] PropertySource with lowest search precedence > DEBUG 08:54:57,576 | org.springframework.core.env.StandardEnvironment | > Adding [systemEnvironment] PropertySource with lowest search precedence > DEBUG 08:54:57,576 | org.springframework.core.env.StandardEnvironment | > Initialized StandardEnvironment with PropertySources > [systemProperties,systemEnvironment] > DEBUG 08:54:57,576 | org.springframework.core.env.StandardEnvironment | > Initializing new StandardEnvironment > DEBUG 08:54:57,576 | org.springframework.core.env.StandardEnvironment | > Adding [systemProperties] PropertySource with lowest search precedence > DEBUG 08:54:57,576 | org.springframework.core.env.StandardEnvironment | > Adding [systemEnvironment] PropertySource with lowest search precedence > DEBUG 08:54:57,576 | org.springframework.core.env.StandardEnvironment | > Initialized StandardEnvironment with PropertySources > [systemProperties,systemEnvironment] > DEBUG 08:54:57,576 | org.springframework.core.env.StandardEnvironment | > Initializing new StandardEnvironment > DEBUG 08:54:57,576 | org.springframework.core.env.StandardEnvironment | > Adding [systemProperties] PropertySource with lowest search precedence > DEBUG 08:54:57,592 | org.springframework.core.env.StandardEnvironment | > Adding [systemEnvironment] PropertySource with lowest search precedence > DEBUG 08:54:57,592 | org.springframework.core.env.StandardEnvironment | > Initialized StandardEnvironment with PropertySources > [systemProperties,systemEnvironment] > DEBUG 08:54:57,592 | org.springframework.beans.BeanUtils | No property editor > [org.springframework.orm.jpa.vendor.DatabaseEditor] found for type > org.springframework.orm.jpa.vendor.Database according to 'Editor' suffix > convention > DEBUG 08:54:57,592 | > org.springframework.beans.factory.support.DefaultListableBeanFactory | > Finished creating instance of bean 'vendorAdapter' > DEBUG 08:54:57,592 | > org.springframework.beans.factory.support.DefaultListableBeanFactory | > Invoking afterPropertiesSet() on bean with name 'entityManagerFactory' > INFO 08:54:57,607 | > org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean | Building > JPA container EntityManagerFactory for persistence unit 'James' > DEBUG 08:54:58,092 | > org.springframework.beans.factory.support.DefaultListableBeanFactory | > Finished creating instance of bean 'entityManagerFactory' > INFO 08:54:58,092 | james.domainlist | Set autodetect to: true > INFO 08:54:58,092 | james.domainlist | Set autodetectIP to: true > DEBUG 08:54:58,092 | > org.springframework.context.annotation.CommonAnnotationBeanPostProcessor | > Invoking init method on bean 'domainlist': public void > org.apache.james.domainlist.jpa.JPADomainList.init() > INFO 08:54:58,529 | > org.springframework.beans.factory.support.DefaultListableBeanFactory | > Destroying singletons in > org.springframework.beans.factory.support.DefaultListableBeanFactory@15780d9: > defining beans > [org.springframework.context.annotation.CommonAnnotationBeanPostProcessor#0,org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor#0,org.apache.james.container.spring.lifecycle.LogEnabledBeanPostProcessor#0,logprovider,org.apache.james.container.spring.bean.factorypostprocessor.ConfigurationBeanFactoryPostProcessor#0,org.apache.james.container.spring.lifecycle.ConfigurableBeanPostProcessor#0,configurationprovider,mailrepositorystore,mailqueuefactory,broker,amqConnectionFactory,blobTransferPolicy,jmsConnectionFactory,jmsTransactionManager,mailprocessor,mailetcontext,mailspooler,mailetloader,matcherloader,agent,producerTemplate,consumerTemplate,jamesCamelContext:beanPostProcessor,jamesCamelContext,org.apache.james.container.spring.bean.factorypostpro cessor.F i leSystemBeanFactoryPostProcessor#0,filesystem,dnsservice,fetchmail,protocolhandlerloader,smtpserver,lmtpserver,pop3server,imapserver,imapProcessor,imapDecoder,imapDecoderFactory,imapEncoder,imapEncoderFactory,org.apache.james.container.spring.bean.factorypostprocessor.MailboxConfigurationBeanFactoryPostProcessor#0,locker,jvm-locker,authenticator,lazyIndex,luceneIndex,ramDirectory,org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor#1,jpa-mailboxmanager,jpa-subscriptionManager,jpa-sessionMapperFactory,jpa-uidProvider,jpa-modSeqProvider,org.springframework.beans.factory.config.PropertyPlaceholderConfigurer#0,datasource,entityManagerFactory,vendorAdapter,org.springframework.beans.factory.config.PropertyPlaceholderConfigurer#1,maildir-mailboxmanager,maildir-subscriptionManager,maildir-sessionMapperFactory,maildirStore,memory-mailboxmanager,memory-subscriptionManager,memory-sessionMapperFactory,mailboxcopier,aclResolver,groupMembershipResolver,james23importer ,org.spr i ngframework.beans.factory.config.PropertyPlaceholderConfigurer#2,org.springframework.beans.factory.config.PropertyPlaceholderConfigurer#3,mbeanserver,serverConnector,registry,restrictedRmiSocketFactory,exporter,usersrepositorymanagement,recipientrewritetablemanagement,domainlistmanagement,mailboxmanagermanagement,mailboxcopiermanagement,james23importermanagement,usersrepository,recipientrewritetable,domainlist,usersrepository23]; root of factory hierarchy > DEBUG 08:54:58,545 | > org.springframework.beans.factory.support.DisposableBeanAdapter | Invoking > destroy() on bean with name 'entityManagerFactory' > INFO 08:54:58,545 | > org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean | Closing > JPA EntityManagerFactory for persistence unit 'James' > DEBUG 08:54:58,545 | > org.springframework.beans.factory.support.DisposableBeanAdapter | Invoking > destroy method 'close' on bean with name 'datasource' > DEBUG 08:54:58,545 | > org.springframework.context.annotation.CommonAnnotationBeanPostProcessor | > Invoking destroy method on bean 'mailqueuefactory': public void > org.apache.james.queue.library.AbstractMailQueueFactory.destroy() > DEBUG 08:54:58,545 | > org.springframework.beans.factory.support.DisposableBeanAdapter | Invoking > destroy() on bean with name 'jmsConnectionFactory' > DEBUG 08:54:58,545 | > org.springframework.beans.factory.support.DefaultListableBeanFactory | > Retrieved dependent beans for bean '(inner bean)#3': [amqConnectionFactory] > DEBUG 08:54:58,545 | > org.springframework.context.annotation.CommonAnnotationBeanPostProcessor | > Invoking destroy method on bean 'broker': public void > org.apache.activemq.xbean.XBeanBrokerService.destroy() throws > java.lang.Exception > INFO 08:54:58,545 | org.apache.activemq.broker.BrokerService | ActiveMQ > Message Broker (james, ID:direct-49713-1331823297045-0:1) is shutting down > INFO 08:54:59,326 | org.apache.activemq.broker.TransportConnector | > Connector tcp://localhost:0 Stopped > INFO 08:54:59,326 | org.apache.activemq.store.kahadb.plist.PListStore | > PListStore:..\var\store\activemq\brokers\james\tmp_storage stopped > DEBUG 08:54:59,326 | org.apache.activemq.store.amq.AMQPersistenceAdapter | > Waitng for checkpoint to complete. > DEBUG 08:54:59,326 | org.apache.activemq.store.amq.AMQPersistenceAdapter | > Checkpoint started. > DEBUG 08:54:59,326 | org.apache.activemq.store.amq.AMQPersistenceAdapter | > Checkpoint done. > DEBUG 08:54:59,342 | org.apache.activemq.store.amq.AMQPersistenceAdapter | > Journal close > INFO 08:54:59,357 | org.apache.activemq.broker.BrokerService | ActiveMQ JMS > Message Broker (james, ID:direct-49713-1331823297045-0:1) stopped > DEBUG 08:54:59,357 | > org.springframework.context.annotation.CommonAnnotationBeanPostProcessor | > Invoking destroy method on bean 'broker': public void > org.apache.activemq.broker.BrokerService.stop() throws java.lang.Exception > DEBUG 08:54:59,357 | > org.springframework.beans.factory.support.DefaultListableBeanFactory | > Retrieved dependent beans for bean '(inner bean)': [broker] > DEBUG 08:54:59,357 | > org.springframework.beans.factory.support.DefaultListableBeanFactory | > Retrieved dependent beans for bean 'amq:policyEntry#22d166': [(inner bean)] > DEBUG 08:54:59,357 | > org.springframework.beans.factory.support.DisposableBeanAdapter | Invoking > destroy() on bean with name 'jamesCamelContext' > INFO 08:54:59,357 | org.apache.camel.impl.DefaultCamelContext | Apache Camel > 2.5.0 (CamelContext:jamesCamelContext) is shutting down > INFO 08:54:59,357 | org.apache.camel.impl.DefaultShutdownStrategy | Starting > to graceful shutdown 0 routes (timeout 300 seconds) > DEBUG 08:54:59,373 | org.apache.camel.impl.DefaultExecutorServiceStrategy | > Created new single thread pool for source: > org.apache.camel.impl.DefaultShutdownStrategy@6399ae with name: ShutdownTask. > -> java.util.concurrent.Executors$FinalizableDelegatedExecutorService@fe9ad1 > DEBUG 08:54:59,373 | org.apache.camel.impl.DefaultShutdownStrategy | There > are 0 routes to shutdown > INFO 08:54:59,373 | org.apache.camel.impl.DefaultShutdownStrategy | Graceful > shutdown of 0 routes completed in 0 seconds > INFO 08:54:59,373 | org.apache.camel.impl.DefaultCamelContext | Uptime: not > started > INFO 08:54:59,373 | org.apache.camel.impl.DefaultCamelContext | Apache Camel > 2.5.0 (CamelContext: jamesCamelContext) is shutdown in 0.016 seconds > -- eric | http://about.echarles.net | @echarles --------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected] --------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
