Eric,
This happens when I'm loading a folder via IMAP. Below is from the logs
where I attempted to access a folder twice.
Thanks,
Adam
DEBUG 20:12:29,333 | james.imapserver | ID=1944274233 Got <tag>: 8
DEBUG 20:12:29,334 | james.imapserver | ID=1944274233 Got <command>: select
DEBUG 20:12:29,494 | james.imapserver | ID=1944274233 Loaded mailbox
#private:agibson:INBOX
DEBUG 20:12:29,660 | james.imapserver | ID=1944274233 Loaded mailbox
#private:agibson:INBOX
INFO 20:13:19,265 | james.imapserver | ID=324168823 Connection established
from 0:0:0:0:0:0:0:1
DEBUG 20:13:19,268 | james.imapserver | ID=324168823 Got <tag>: 1
DEBUG 20:13:19,268 | james.imapserver | ID=324168823 Got <command>:
capability
DEBUG 20:13:19,269 | james.imapserver | ID=324168823 No mailbox selected
DEBUG 20:13:19,272 | james.imapserver | ID=324168823 Got <tag>: 2
DEBUG 20:13:19,272 | james.imapserver | ID=324168823 Got <command>:
authenticate
DEBUG 20:13:25,061 | james.imapserver | ID=324168823 INBOX exists. No need
to create it.
DEBUG 20:13:25,063 | james.imapserver | ID=324168823 Got <tag>: 3
DEBUG 20:13:25,063 | james.imapserver | ID=324168823 Got <command>: STATUS
DEBUG 20:13:25,065 | james.imapserver | ID=324168823 Status called on
mailbox named ${FOLDERNAME}
DEBUG 20:13:25,229 | james.imapserver | ID=324168823 Loaded mailbox
#private:${FOLDERNAME}
DEBUG 20:13:25,398 | james.imapserver | ID=324168823 Error while processing
imap request
<openjpa-2.1.0-r422266:1071316 nonfatal user error>
org.apache.openjpa.persistence.InvalidStateException: This operation cannot
be performed while a Transaction is active.
at org.apache.openjpa.kernel.BrokerImpl.close(BrokerImpl.java:4304)
at
org.apache.openjpa.kernel.DelegatingBroker.close(DelegatingBroker.java:1346)
at
org.apache.openjpa.persistence.EntityManagerImpl.close(EntityManagerImpl.java:1284)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:601)
at
org.springframework.orm.jpa.ExtendedEntityManagerCreator$ExtendedEntityManagerInvocationHandler.invoke(ExtendedEntityManagerCreator.java:365)
at $Proxy64.close(Unknown Source)
at
org.apache.james.mailbox.jpa.mail.JPAUidProvider.lastUid(JPAUidProvider.java:58)
at
org.apache.james.mailbox.store.mail.AbstractMessageMapper.getLastUid(AbstractMessageMapper.java:66)
at
org.apache.james.mailbox.store.StoreMessageManager.getMetaData(StoreMessageManager.java:437)
at
org.apache.james.imap.processor.StatusProcessor.doProcess(StatusProcessor.java:71)
at
org.apache.james.imap.processor.StatusProcessor.doProcess(StatusProcessor.java:38)
at
org.apache.james.imap.processor.AbstractMailboxProcessor.doProcess(AbstractMailboxProcessor.java:100)
at
org.apache.james.imap.processor.AbstractMailboxProcessor.process(AbstractMailboxProcessor.java:89)
at
org.apache.james.imap.processor.AbstractMailboxProcessor.doProcess(AbstractMailboxProcessor.java:83)
at
org.apache.james.imap.processor.AbstractMailboxProcessor.doProcess(AbstractMailboxProcessor.java:66)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:52)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imapserver.netty.ImapChannelUpstreamHandler.messageReceived(ImapChannelUpstreamHandler.java:181)
at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:296)
at
org.jboss.netty.handler.codec.frame.FrameDecoder.unfoldAndFireMessageReceived(FrameDecoder.java:327)
at
org.jboss.netty.handler.codec.frame.FrameDecoder.callDecode(FrameDecoder.java:305)
at
org.jboss.netty.handler.codec.frame.FrameDecoder.messageReceived(FrameDecoder.java:207)
at
org.jboss.netty.handler.execution.ChannelUpstreamEventRunnable.run(ChannelUpstreamEventRunnable.java:44)
at
org.jboss.netty.handler.execution.OrderedMemoryAwareThreadPoolExecutor$ChildExecutor.run(OrderedMemoryAwareThreadPoolExecutor.java:312)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
at java.lang.Thread.run(Thread.java:722)
INFO 20:13:25,402 | james.imapserver | ID=324168823 Connection closed for
0:0:0:0:0:0:0:1
INFO 20:13:25,406 | james.imapserver | ID=1390947962 Connection
established from 0:0:0:0:0:0:0:1
DEBUG 20:13:25,409 | james.imapserver | ID=1390947962 Got <tag>: 1
DEBUG 20:13:25,409 | james.imapserver | ID=1390947962 Got <command>:
capability
DEBUG 20:13:25,410 | james.imapserver | ID=1390947962 No mailbox selected
DEBUG 20:13:25,412 | james.imapserver | ID=1390947962 Got <tag>: 2
DEBUG 20:13:25,413 | james.imapserver | ID=1390947962 Got <command>:
authenticate
DEBUG 20:13:25,619 | james.imapserver | ID=1390947962 INBOX exists. No need
to create it.
DEBUG 20:13:25,621 | james.imapserver | ID=1390947962 Got <tag>: 3
DEBUG 20:13:25,621 | james.imapserver | ID=1390947962 Got <command>: STATUS
DEBUG 20:13:25,622 | james.imapserver | ID=1390947962 Status called on
mailbox named ${FOLDERNAME}
DEBUG 20:13:25,783 | james.imapserver | ID=1390947962 Loaded mailbox
#private: ${FOLDER NAME}
DEBUG 20:13:25,936 | james.imapserver | ID=1390947962 Error while
processing imap request
<openjpa-2.1.0-r422266:1071316 nonfatal user error>
org.apache.openjpa.persistence.InvalidStateException: This operation cannot
be performed while a Transaction is active.
at org.apache.openjpa.kernel.BrokerImpl.close(BrokerImpl.java:4304)
at
org.apache.openjpa.kernel.DelegatingBroker.close(DelegatingBroker.java:1346)
at
org.apache.openjpa.persistence.EntityManagerImpl.close(EntityManagerImpl.java:1284)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:601)
at
org.springframework.orm.jpa.ExtendedEntityManagerCreator$ExtendedEntityManagerInvocationHandler.invoke(ExtendedEntityManagerCreator.java:365)
at $Proxy64.close(Unknown Source)
at
org.apache.james.mailbox.jpa.mail.JPAUidProvider.lastUid(JPAUidProvider.java:58)
at
org.apache.james.mailbox.store.mail.AbstractMessageMapper.getLastUid(AbstractMessageMapper.java:66)
at
org.apache.james.mailbox.store.StoreMessageManager.getMetaData(StoreMessageManager.java:437)
at
org.apache.james.imap.processor.StatusProcessor.doProcess(StatusProcessor.java:71)
at
org.apache.james.imap.processor.StatusProcessor.doProcess(StatusProcessor.java:38)
at
org.apache.james.imap.processor.AbstractMailboxProcessor.doProcess(AbstractMailboxProcessor.java:100)
at
org.apache.james.imap.processor.AbstractMailboxProcessor.process(AbstractMailboxProcessor.java:89)
at
org.apache.james.imap.processor.AbstractMailboxProcessor.doProcess(AbstractMailboxProcessor.java:83)
at
org.apache.james.imap.processor.AbstractMailboxProcessor.doProcess(AbstractMailboxProcessor.java:66)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:52)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imap.processor.base.AbstractChainedProcessor.process(AbstractChainedProcessor.java:54)
at
org.apache.james.imapserver.netty.ImapChannelUpstreamHandler.messageReceived(ImapChannelUpstreamHandler.java:181)
at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:296)
at
org.jboss.netty.handler.codec.frame.FrameDecoder.unfoldAndFireMessageReceived(FrameDecoder.java:327)
at
org.jboss.netty.handler.codec.frame.FrameDecoder.callDecode(FrameDecoder.java:305)
at
org.jboss.netty.handler.codec.frame.FrameDecoder.messageReceived(FrameDecoder.java:207)
at
org.jboss.netty.handler.execution.ChannelUpstreamEventRunnable.run(ChannelUpstreamEventRunnable.java:44)
at
org.jboss.netty.handler.execution.OrderedMemoryAwareThreadPoolExecutor$ChildExecutor.run(OrderedMemoryAwareThreadPoolExecutor.java:312)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
at java.lang.Thread.run(Thread.java:722)
INFO 20:13:25,940 | james.imapserver | ID=1390947962 Connection closed for
0:0:0:0:0:0:0:1
INFO 20:14:28,114 | james.imapserver | ID=1954122653 Connection
established from 0:0:0:0:0:0:0:1
DEBUG 20:14:28,150 | james.imapserver | ID=1954122653 Got <tag>: 1
DEBUG 20:14:28,150 | james.imapserver | ID=1954122653 Got <command>:
capability
DEBUG 20:14:28,151 | james.imapserver | ID=1954122653 No mailbox selected
DEBUG 20:14:28,156 | james.imapserver | ID=1954122653 Got <tag>: 2
DEBUG 20:14:28,156 | james.imapserver | ID=1954122653 Got <command>:
authenticate
DEBUG 20:14:28,345 | james.imapserver | ID=1954122653 INBOX exists. No need
to create it.
DEBUG 20:14:28,347 | james.imapserver | ID=1954122653 Got <tag>: 3
DEBUG 20:14:28,347 | james.imapserver | ID=1954122653 Got <command>: ENABLE
DEBUG 20:14:28,349 | james.imapserver | ID=1954122653 No mailbox selected
DEBUG 20:14:28,351 | james.imapserver | ID=1954122653 Got <tag>: 4
On Thu, Apr 26, 2012 at 3:20 PM, Eric Charles <[email protected]> wrote:
> Hi Adam,
>
> There has been some changes between M2 and M4 regarding the way uid is
> generated and how the lock is managed for it.
>
> Does this arise further to a particular operation (I suppose it is further
> a smtp in)? (look at the log lines before the stacktrace).
>
> Thx,
> Eric
>
>
> On 04/26/2012 02:49 AM, Adam Gibson wrote:
>
>> Hello,
>> I currently have a stack akin to with openjpa.streaming=false :
>> https://issues.apache.org/**jira/browse/JAMES-1138<https://issues.apache.org/jira/browse/JAMES-1138>
>>
>> My setup is as follows:
>> Mail server version: beta-4
>> OS: Centos 5.7
>> Java version:
>> Java(TM) SE Runtime Environment (build 1.7.0_02-b13)
>> Java HotSpot(TM) 64-Bit Server VM (build 22.0-b10, mixed mode)
>> Mysql version:
>> 5.5.22 MySQL Community Server
>>
>> JDBC driver:
>> mysql-connector-java-5.1.18.**jar
>>
>> If it helps, I am currently running a beta-2 server in production with no
>> problems and the exact same settings. I turned my log levels up to DEBUG
>> and picked up this stack trace. Any help will be appreciated.
>>
>> org.apache.openjpa.**persistence.**InvalidStateException: This operation
>> cannot be performed while a Transaction is active.
>> at org.apache.openjpa.kernel.**BrokerImpl.close(BrokerImpl.**
>> java:4304)
>> at
>> org.apache.openjpa.kernel.**DelegatingBroker.close(**
>> DelegatingBroker.java:1346)
>> at
>> org.apache.openjpa.**persistence.EntityManagerImpl.**
>> close(EntityManagerImpl.java:**1284)
>> at sun.reflect.**NativeMethodAccessorImpl.**invoke0(Native Method)
>> at
>> sun.reflect.**NativeMethodAccessorImpl.**invoke(**
>> NativeMethodAccessorImpl.java:**57)
>> at
>> sun.reflect.**DelegatingMethodAccessorImpl.**invoke(**
>> DelegatingMethodAccessorImpl.**java:43)
>> at java.lang.reflect.Method.**invoke(Method.java:601)
>> at
>> org.springframework.orm.jpa.**ExtendedEntityManagerCreator$**
>> ExtendedEntityManagerInvocatio**nHandler.invoke(**
>> ExtendedEntityManagerCreator.**java:365)
>> at $Proxy64.close(Unknown Source)
>> at
>> org.apache.james.mailbox.jpa.**mail.JPAUidProvider.lastUid(**
>> JPAUidProvider.java:58)
>> at
>> org.apache.james.mailbox.**store.mail.**AbstractMessageMapper.**
>> getLastUid(**AbstractMessageMapper.java:66)
>> at
>> org.apache.james.mailbox.**store.StoreMessageManager.**getMetaData(**
>> StoreMessageManager.java:437)
>> at
>> org.apache.james.imap.**processor.StatusProcessor.**
>> doProcess(StatusProcessor.**java:71)
>> at
>> org.apache.james.imap.**processor.StatusProcessor.**
>> doProcess(StatusProcessor.**java:38)
>> at
>> org.apache.james.imap.**processor.**AbstractMailboxProcessor.**doProcess(
>> **AbstractMailboxProcessor.java:**100)
>> at
>> org.apache.james.imap.**processor.**AbstractMailboxProcessor.**process(**
>> AbstractMailboxProcessor.java:**89)
>> at
>> org.apache.james.imap.**processor.**AbstractMailboxProcessor.**doProcess(
>> **AbstractMailboxProcessor.java:**83)
>> at
>> org.apache.james.imap.**processor.**AbstractMailboxProcessor.**doProcess(
>> **AbstractMailboxProcessor.java:**66)
>> at
>> org.apache.james.imap.**processor.base.**AbstractChainedProcessor.**
>> process(**AbstractChainedProcessor.java:**52)
>> at
>> org.apache.james.imap.**processor.base.**AbstractChainedProcessor.**
>> process(**AbstractChainedProcessor.java:**54)
>> at
>> org.apache.james.imap.**processor.base.**AbstractChainedProcessor.**
>> process(**AbstractChainedProcessor.java:**54)
>> at
>> org.apache.james.imap.**processor.base.**AbstractChainedProcessor.**
>> process(**AbstractChainedProcessor.java:**54)
>> at
>> org.apache.james.imap.**processor.base.**AbstractChainedProcessor.**
>> process(**AbstractChainedProcessor.java:**54)
>> at
>> org.apache.james.imap.**processor.base.**AbstractChainedProcessor.**
>> process(**AbstractChainedProcessor.java:**54)
>> at
>> org.apache.james.imap.**processor.base.**AbstractChainedProcessor.**
>> process(**AbstractChainedProcessor.java:**54)
>> at
>> org.apache.james.imap.**processor.base.**AbstractChainedProcessor.**
>> process(**AbstractChainedProcessor.java:**54)
>> at
>> org.apache.james.imap.**processor.base.**AbstractChainedProcessor.**
>> process(**AbstractChainedProcessor.java:**54)
>> at
>> org.apache.james.imap.**processor.base.**AbstractChainedProcessor.**
>> process(**AbstractChainedProcessor.java:**54)
>> at
>> org.apache.james.imap.**processor.base.**AbstractChainedProcessor.**
>> process(**AbstractChainedProcessor.java:**54)
>> at
>> org.apache.james.imap.**processor.base.**AbstractChainedProcessor.**
>> process(**AbstractChainedProcessor.java:**54)
>> at
>> org.apache.james.imap.**processor.base.**AbstractChainedProcessor.**
>> process(**AbstractChainedProcessor.java:**54)
>> at
>> org.apache.james.imap.**processor.base.**AbstractChainedProcessor.**
>> process(**AbstractChainedProcessor.java:**54)
>> at
>> org.apache.james.imap.**processor.base.**AbstractChainedProcessor.**
>> process(**AbstractChainedProcessor.java:**54)
>> at
>> org.apache.james.imap.**processor.base.**AbstractChainedProcessor.**
>> process(**AbstractChainedProcessor.java:**54)
>> at
>> org.apache.james.imap.**processor.base.**AbstractChainedProcessor.**
>> process(**AbstractChainedProcessor.java:**54)
>> at
>> org.apache.james.imap.**processor.base.**AbstractChainedProcessor.**
>> process(**AbstractChainedProcessor.java:**54)
>> at
>> org.apache.james.imapserver.**netty.**ImapChannelUpstreamHandler.**
>> messageReceived(**ImapChannelUpstreamHandler.**java:181)
>> at
>> org.jboss.netty.channel.**Channels.fireMessageReceived(**
>> Channels.java:296)
>> at
>> org.jboss.netty.handler.codec.**frame.FrameDecoder.**
>> unfoldAndFireMessageReceived(**FrameDecoder.java:327)
>> at
>> org.jboss.netty.handler.codec.**frame.FrameDecoder.callDecode(**
>> FrameDecoder.java:305)
>> at
>> org.jboss.netty.handler.codec.**frame.FrameDecoder.**
>> messageReceived(FrameDecoder.**java:207)
>> at
>> org.jboss.netty.handler.**execution.**ChannelUpstreamEventRunnable.**run(
>> **ChannelUpstreamEventRunnable.**java:44)
>> at
>> org.jboss.netty.handler.**execution.**OrderedMemoryAwareThreadPoolEx**
>> ecutor$ChildExecutor.run(**OrderedMemoryAwareThreadPoolEx**
>> ecutor.java:312)
>> at
>> java.util.concurrent.**ThreadPoolExecutor.runWorker(**
>> ThreadPoolExecutor.java:1110)
>> at
>> java.util.concurrent.**ThreadPoolExecutor$Worker.run(**
>> ThreadPoolExecutor.java:603)
>> at java.lang.Thread.run(Thread.**java:722)
>>
>> Thanks,
>> Adam
>>
>>
> --
> eric | http://about.echarles.net | @echarles
>
> ------------------------------**------------------------------**---------
> To unsubscribe, e-mail:
> server-user-unsubscribe@james.**apache.org<[email protected]>
> For additional commands, e-mail:
> [email protected].**org<[email protected]>
>
>
