james-server-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Amichai Rothman (JIRA)" <server-...@james.apache.org>
Subject [jira] Commented: (JAMES-900) NullPointerException during local delivery spooling
Date Fri, 08 May 2009 23:22:45 GMT

    [ https://issues.apache.org/jira/browse/JAMES-900?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=12707554#action_12707554
] 

Amichai Rothman commented on JAMES-900:
---------------------------------------

I've made good progress analyzing the logs some more - I've found a correspondence between
these errors and errors in the mailet logs (see below). The mail identifier in the last line
is the one that appears also in the spoolmanager logs. So I suppose the bug can be rephrased
as: a message with a mime format error cannot be stored in the error store.

Note that I first tried a simple fix for #1 and #2, namely adding the synchronized modifier
to the methods MessageReferenceTracker.getWrapped(),  getWrappedMessageForWriting() and getWrappedMessage()
in the MimeMessageCopyOnWriteProxy class. u might want to make these changes anyway because
the code as it is does have potential bugs without them, even if not this specific error.



02/05/09 04:07:50 INFO  James.Mailet: Error while storing mail.
javax.mail.MessagingException: Exception caught while storing mail Container: ;
  nested exception is:
	javax.mail.MessagingException: IOException while copying message;
  nested exception is:
	com.sun.mail.util.DecodingException: BASE64Decoder: Error in encoded stream: needed 4 valid
base64 characters but only got 2 before EOF, the 10 most recent characters were: "\130\190\130\179\130\162\129B\r\n"
	at org.apache.james.mailrepository.JDBCMailRepository.store(JDBCMailRepository.java:764)
	at org.apache.james.transport.mailets.ToMultiRepository.storeMail(ToMultiRepository.java:230)
	at org.apache.james.transport.mailets.ToMultiRepository.service(ToMultiRepository.java:154)
	at org.apache.james.transport.mailets.LocalDelivery.service(LocalDelivery.java:66)
	at org.apache.james.transport.LinearProcessor.service(LinearProcessor.java:424)
	at org.apache.james.transport.JamesSpoolManager.process(JamesSpoolManager.java:405)
	at org.apache.james.transport.JamesSpoolManager.run(JamesSpoolManager.java:309)
	at java.lang.Thread.run(Unknown Source)
Caused by: javax.mail.MessagingException: IOException while copying message;
  nested exception is:
	com.sun.mail.util.DecodingException: BASE64Decoder: Error in encoded stream: needed 4 valid
base64 characters but only got 2 before EOF, the 10 most recent characters were: "\130\190\130\179\130\162\129B\r\n"
	at org.apache.james.core.MimeMessageWrapper.<init>(MimeMessageWrapper.java:149)
	at org.apache.james.core.MimeMessageCopyOnWriteProxy.getWrappedMessageForWriting(MimeMessageCopyOnWriteProxy.java:149)
	at org.apache.james.core.MimeMessageCopyOnWriteProxy.saveChanges(MimeMessageCopyOnWriteProxy.java:687)
	at org.apache.james.core.MimeMessageUtil.writeToInternal(MimeMessageUtil.java:84)
	at org.apache.james.core.MimeMessageUtil.writeTo(MimeMessageUtil.java:70)
	at org.apache.james.core.MimeMessageUtil.writeTo(MimeMessageUtil.java:50)
	at org.apache.james.mailrepository.MessageInputStream.writeStream(MessageInputStream.java:112)
	at org.apache.james.mailrepository.MessageInputStream.<init>(MessageInputStream.java:82)
	at org.apache.james.mailrepository.JDBCMailRepository.store(JDBCMailRepository.java:718)
	... 7 more
Caused by: com.sun.mail.util.DecodingException: BASE64Decoder: Error in encoded stream: needed
4 valid base64 characters but only got 2 before EOF, the 10 most recent characters were: "\130\190\130\179\130\162\129B\r\n"
	at com.sun.mail.util.BASE64DecoderStream.decode(BASE64DecoderStream.java:252)
	at com.sun.mail.util.BASE64DecoderStream.read(BASE64DecoderStream.java:140)
	at java.io.FilterInputStream.read(Unknown Source)
	at org.apache.james.core.MimeMessageUtil.copyStream(MimeMessageUtil.java:167)
	at org.apache.james.core.MimeMessageUtil.writeMessageBodyTo(MimeMessageUtil.java:152)
	at org.apache.james.core.MimeMessageUtil.writeToInternal(MimeMessageUtil.java:90)
	at org.apache.james.core.MimeMessageWrapper.writeTo(MimeMessageWrapper.java:281)
	at org.apache.james.core.MimeMessageWrapper.writeTo(MimeMessageWrapper.java:257)
	at org.apache.james.core.MimeMessageWrapper.writeTo(MimeMessageWrapper.java:242)
	at org.apache.james.core.MimeMessageWrapper.<init>(MimeMessageWrapper.java:140)
	... 15 more
02/05/09 04:07:50 INFO  James.Mailet: ToRepository: Storing mail Mail1241237254190-37952 in
db://maildb/deadletter/error



> NullPointerException during local delivery spooling
> ---------------------------------------------------
>
>                 Key: JAMES-900
>                 URL: https://issues.apache.org/jira/browse/JAMES-900
>             Project: JAMES Server
>          Issue Type: Bug
>          Components: SpoolManager & Processors
>    Affects Versions: 2.3.1
>         Environment: Windows XP, Sun JDK 1.6.0_12, MySQL 5.0 backend
>            Reporter: Amichai Rothman
>
> The exception below occurs 1-2 times a day on a server handling between a few hundred
and a few thousands of messages per day.
> I took a short look at the code, and it looks like there's a MimeMessageCopyOnWriteProxy
wrapping a null message. A few possible causes:
> - MessageReferenceTracker.getWrapped() should be synchronized, as the field is neither
final nor volatile and so it's possible that a thread other than the one which creates it
will see a null wrapped message.
> - the refCount field is not properly synchronized nor volatile so methods which set/replace
its value (private constructor, getWrappedMessageForWriting) may cause some threads to see
the new instance and some the old one. this can result in buggy reference counting, which
can result in the wrapped message being set to null (when reference count is decreased too
much) and then being accessed.
> - a MailImpl is being constructed (or copy-constructed) somewhere with a null message
(which is passed on to the proxy class).
> - something completely different.
> The stacktrace from spoolmanager log:
> 25/04/09 02:00:58 ERROR spoolmanager.transport: Exception calling LocalDelivery: Exception
spooling message: Exception caught while storing mail Container: 
> javax.mail.MessagingException: Exception spooling message: Exception caught while storing
mail Container: ;
>   nested exception is:
> 	javax.mail.MessagingException: Exception caught while storing mail Container: ;
>   nested exception is:
> 	java.lang.NullPointerException
> 	at org.apache.james.James.sendMail(James.java:501)
> 	at org.apache.james.James.sendMail(James.java:454)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
> 	at java.lang.reflect.Method.invoke(Unknown Source)
> 	at org.apache.avalon.phoenix.components.application.BlockInvocationHandler.invoke(BlockInvocationHandler.java:134)
> 	at $Proxy9.sendMail(Unknown Source)
> 	at org.apache.james.transport.mailets.ToMultiRepository.service(ToMultiRepository.java:183)
> 	at org.apache.james.transport.mailets.LocalDelivery.service(LocalDelivery.java:66)
> 	at org.apache.james.transport.LinearProcessor.service(LinearProcessor.java:424)
> 	at org.apache.james.transport.JamesSpoolManager.process(JamesSpoolManager.java:405)
> 	at org.apache.james.transport.JamesSpoolManager.run(JamesSpoolManager.java:309)
> 	at java.lang.Thread.run(Unknown Source)
> Caused by: javax.mail.MessagingException: Exception caught while storing mail Container:
;
>   nested exception is:
> 	java.lang.NullPointerException
> 	at org.apache.james.mailrepository.JDBCMailRepository.store(JDBCMailRepository.java:764)
> 	at org.apache.james.mailrepository.JDBCSpoolRepository.store(JDBCSpoolRepository.java:240)
> 	at org.apache.james.mailrepository.MailStoreSpoolRepository.store(MailStoreSpoolRepository.java:126)
> 	at sun.reflect.GeneratedMethodAccessor10.invoke(Unknown Source)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
> 	at java.lang.reflect.Method.invoke(Unknown Source)
> 	at org.apache.avalon.phoenix.components.application.BlockInvocationHandler.invoke(BlockInvocationHandler.java:134)
> 	at $Proxy5.store(Unknown Source)
> 	at org.apache.james.James.sendMail(James.java:493)
> 	... 13 more
> Caused by: java.lang.NullPointerException
> 	at org.apache.james.core.MimeMessageUtil.calculateMessageSize(MimeMessageUtil.java:277)
> 	at org.apache.james.core.MimeMessageUtil.getMessageSize(MimeMessageUtil.java:261)
> 	at org.apache.james.core.MimeMessageCopyOnWriteProxy.getMessageSize(MimeMessageCopyOnWriteProxy.java:745)
> 	at org.apache.james.core.MimeMessageUtil.getMessageSize(MimeMessageUtil.java:257)
> 	at org.apache.james.core.MailImpl.getMessageSize(MailImpl.java:372)
> 	at org.apache.james.mailrepository.MessageInputStream.<init>(MessageInputStream.java:53)
> 	at org.apache.james.mailrepository.JDBCMailRepository.store(JDBCMailRepository.java:718)
> 	... 21 more
> javax.mail.MessagingException: Exception caught while storing mail Container: ;
>   nested exception is:
> 	java.lang.NullPointerException
> 	at org.apache.james.mailrepository.JDBCMailRepository.store(JDBCMailRepository.java:764)
> 	at org.apache.james.mailrepository.JDBCSpoolRepository.store(JDBCSpoolRepository.java:240)
> 	at org.apache.james.mailrepository.MailStoreSpoolRepository.store(MailStoreSpoolRepository.java:126)
> 	at sun.reflect.GeneratedMethodAccessor10.invoke(Unknown Source)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
> 	at java.lang.reflect.Method.invoke(Unknown Source)
> 	at org.apache.avalon.phoenix.components.application.BlockInvocationHandler.invoke(BlockInvocationHandler.java:134)
> 	at $Proxy5.store(Unknown Source)
> 	at org.apache.james.James.sendMail(James.java:493)
> 	at org.apache.james.James.sendMail(James.java:454)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
> 	at java.lang.reflect.Method.invoke(Unknown Source)
> 	at org.apache.avalon.phoenix.components.application.BlockInvocationHandler.invoke(BlockInvocationHandler.java:134)
> 	at $Proxy9.sendMail(Unknown Source)
> 	at org.apache.james.transport.mailets.ToMultiRepository.service(ToMultiRepository.java:183)
> 	at org.apache.james.transport.mailets.LocalDelivery.service(LocalDelivery.java:66)
> 	at org.apache.james.transport.LinearProcessor.service(LinearProcessor.java:424)
> 	at org.apache.james.transport.JamesSpoolManager.process(JamesSpoolManager.java:405)
> 	at org.apache.james.transport.JamesSpoolManager.run(JamesSpoolManager.java:309)
> 	at java.lang.Thread.run(Unknown Source)
> Caused by: java.lang.NullPointerException
> 	at org.apache.james.core.MimeMessageUtil.calculateMessageSize(MimeMessageUtil.java:277)
> 	at org.apache.james.core.MimeMessageUtil.getMessageSize(MimeMessageUtil.java:261)
> 	at org.apache.james.core.MimeMessageCopyOnWriteProxy.getMessageSize(MimeMessageCopyOnWriteProxy.java:745)
> 	at org.apache.james.core.MimeMessageUtil.getMessageSize(MimeMessageUtil.java:257)
> 	at org.apache.james.core.MailImpl.getMessageSize(MailImpl.java:372)
> 	at org.apache.james.mailrepository.MessageInputStream.<init>(MessageInputStream.java:53)
> 	at org.apache.james.mailrepository.JDBCMailRepository.store(JDBCMailRepository.java:718)
> 	... 21 more
> java.lang.NullPointerException
> 	at org.apache.james.core.MimeMessageUtil.calculateMessageSize(MimeMessageUtil.java:277)
> 	at org.apache.james.core.MimeMessageUtil.getMessageSize(MimeMessageUtil.java:261)
> 	at org.apache.james.core.MimeMessageCopyOnWriteProxy.getMessageSize(MimeMessageCopyOnWriteProxy.java:745)
> 	at org.apache.james.core.MimeMessageUtil.getMessageSize(MimeMessageUtil.java:257)
> 	at org.apache.james.core.MailImpl.getMessageSize(MailImpl.java:372)
> 	at org.apache.james.mailrepository.MessageInputStream.<init>(MessageInputStream.java:53)
> 	at org.apache.james.mailrepository.JDBCMailRepository.store(JDBCMailRepository.java:718)
> 	at org.apache.james.mailrepository.JDBCSpoolRepository.store(JDBCSpoolRepository.java:240)
> 	at org.apache.james.mailrepository.MailStoreSpoolRepository.store(MailStoreSpoolRepository.java:126)
> 	at sun.reflect.GeneratedMethodAccessor10.invoke(Unknown Source)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
> 	at java.lang.reflect.Method.invoke(Unknown Source)
> 	at org.apache.avalon.phoenix.components.application.BlockInvocationHandler.invoke(BlockInvocationHandler.java:134)
> 	at $Proxy5.store(Unknown Source)
> 	at org.apache.james.James.sendMail(James.java:493)
> 	at org.apache.james.James.sendMail(James.java:454)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
> 	at java.lang.reflect.Method.invoke(Unknown Source)
> 	at org.apache.avalon.phoenix.components.application.BlockInvocationHandler.invoke(BlockInvocationHandler.java:134)
> 	at $Proxy9.sendMail(Unknown Source)
> 	at org.apache.james.transport.mailets.ToMultiRepository.service(ToMultiRepository.java:183)
> 	at org.apache.james.transport.mailets.LocalDelivery.service(LocalDelivery.java:66)
> 	at org.apache.james.transport.LinearProcessor.service(LinearProcessor.java:424)
> 	at org.apache.james.transport.JamesSpoolManager.process(JamesSpoolManager.java:405)
> 	at org.apache.james.transport.JamesSpoolManager.run(JamesSpoolManager.java:309)
> 	at java.lang.Thread.run(Unknown Source)

-- 
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.


---------------------------------------------------------------------
To unsubscribe, e-mail: server-dev-unsubscribe@james.apache.org
For additional commands, e-mail: server-dev-help@james.apache.org


Mime
View raw message