On 04/26/2012 10:22 PM, Adam Gibson wrote:
Eric,
This happens when I'm loading a folder via IMAP. Below is from the logs
where I attempted to access a folder twice.
Adam, You mean you access the same mailbox twice?
Do you perform this with the same client? What is the delay between the
2 access requests?
Thx,
Eric
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]>
--
eric | http://about.echarles.net | @echarles
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
