[ebxmlms-general] not getting read acknowledgements from hermes

[Arnstein V. <arn...@ki...>]

Hi!=20
I've been trying to set up Hermes to recieve messages from the ebMail =
client application, and send read acknowledgements, but it looks like =
Hermes isn't sending the requested acks. =20
I check off "request read reciept" in ebMail (using the default PO =
example plugin). Looking at the message repository for Hermes, it seems =
like Hermes is preparing the acknowledgements, it just doesn't seem like =
it gets sent. Am I missing something...?
Here is some of the log data for the reception of the message from =
ebMail:=20
=20
-18 11:47:35,556 DEBUG [Thread-12]: polling mail server for messages=20
2005-11-18 11:47:35,556 DEBUG [Thread-12]: =3D> Mail.receive=20
2005-11-18 11:47:35,556 INFO [Thread-12]: Receiving pop3 messages from =
pop3.domeneshop.no<INBOX>=20
2005-11-18 11:47:38,748 DEBUG [Thread-12]: <=3D Mail.receive=20
2005-11-18 11:47:38,748 DEBUG [Thread-12]: got 1 messages=20
2005-11-18 11:47:38,748 INFO [Thread-12]: Received a message from SMTP=20
2005-11-18 11:47:38,748 DEBUG [Thread-12]: =3D> =
MessageServiceHandler.onMessage=20
2005-11-18 11:47:38,748 DEBUG [Thread-12]: =3D> =
MessageServiceHandler.dispatchMessage=20
2005-11-18 11:47:38,748 DEBUG [Thread-12]: =3D> =
MessageServiceHandler.hasSignature=20
2005-11-18 11:47:38,748 DEBUG [Thread-12]: <=3D =
MessageServiceHandler.hasSignature=20
2005-11-18 11:47:38,748 DEBUG [Thread-12]: validating incoming ebXML =
message=20
2005-11-18 11:47:38,758 DEBUG [Thread-12]: validation of incoming =
message ok=20
2005-11-18 11:47:38,768 DEBUG [Thread-12]: =3D> =
MessageServer.hasReceived=20
2005-11-18 11:47:38,768 DEBUG [Thread-12]: =3D> =
DbConnectionPool.getConnection=20
2005-11-18 11:47:38,768 DEBUG [Thread-12]: <=3D =
DbConnectionPool.getConnection=20
2005-11-18 11:47:38,768 DEBUG [Thread-12]: <=3D =
MessageServer.hasReceived=20
2005-11-18 11:47:38,768 DEBUG [Thread-12]: message has an AckReq=20
2005-11-18 11:47:38,768 DEBUG [Thread-12]: =3D> =
MessageServer.hasReceived=20
2005-11-18 11:47:38,768 DEBUG [Thread-12]: <=3D =
MessageServer.hasReceived=20
2005-11-18 11:47:38,768 DEBUG [Thread-12]: message has not been received =
previously=20
2005-11-18 11:47:38,768 DEBUG [Thread-12]: final sequence number in =
store: -9998=20
2005-11-18 11:47:38,768 DEBUG [Thread-12]: =3D> MessageServer.store=20
2005-11-18 11:47:38,768 DEBUG [Thread-12]: Message haven't persisted=20
2005-11-18 11:47:38,768 DEBUG [Thread-12]: Try to persist to =
R0000\message32784=20
2005-11-18 11:47:38,778 DEBUG [Thread-12]: Message Persisted to =
R0000\message32784 on persitence handler=20
2005-11-18 11:47:38,778 DEBUG [Thread-12]: insert into messagestore =
database=20
2005-11-18 11:47:38,778 DEBUG [Thread-12]: <=3D MessageServer.store=20
2005-11-18 11:47:38,778 DEBUG [Thread-12]: =3D> =
MessageServiceHandler.generateAcknowledgment=20
2005-11-18 11:47:38,818 DEBUG [Thread-12]: <=3D =
MessageServiceHandler.generateAcknowledgment=20
2005-11-18 11:47:38,818 DEBUG [Thread-12]: =3D> Transaction.commit =
(txID: #7)=20
2005-11-18 11:47:38,818 DEBUG [Thread-12]: =3D> =
DbConnectionPool.freeConnection=20
2005-11-18 11:47:38,818 DEBUG [Thread-12]: <=3D =
DbConnectionPool.freeConnection=20
2005-11-18 11:47:38,818 DEBUG [Thread-12]: <=3D Transaction.commit=20
2005-11-18 11:47:38,818 DEBUG [Thread-12]: final sequence number in =
store: -9999=20
2005-11-18 11:47:38,818 DEBUG [Thread-12]: =3D> MessageServer.store=20
2005-11-18 11:47:38,818 DEBUG [Thread-12]: insert into reftomessage =
database=20
2005-11-18 11:47:38,818 DEBUG [Thread-12]: =3D> =
DbConnectionPool.getConnection=20
2005-11-18 11:47:38,818 DEBUG [Thread-12]: <=3D =
DbConnectionPool.getConnection=20
2005-11-18 11:47:38,818 DEBUG [Thread-12]: Message haven't persisted=20
2005-11-18 11:47:38,818 DEBUG [Thread-12]: Try to persist to =
R0000\message32785=20
2005-11-18 11:47:38,828 DEBUG [Thread-12]: Message Persisted to =
R0000\message32785 on persitence handler=20
2005-11-18 11:47:38,828 DEBUG [Thread-12]: insert into messagestore =
database=20
2005-11-18 11:47:38,828 DEBUG [Thread-12]: <=3D MessageServer.store=20
2005-11-18 11:47:38,828 DEBUG [Thread-12]: Ack replied synchronously=20
2005-11-18 11:47:38,828 DEBUG [Thread-12]: =3D> =
MessageServer.logReceivedMessage=20
2005-11-18 11:47:38,828 DEBUG [Thread-12]: <=3D =
MessageServer.logReceivedMessage=20
2005-11-18 11:47:38,828 DEBUG [Thread-12]: <=3D =
MessageServiceHandler.dispatchMessage=20
2005-11-18 11:47:38,828 DEBUG [Thread-12]: =3D> Transaction.commit =
(txID: #7)=20
2005-11-18 11:47:38,828 DEBUG [Thread-12]: =3D> =
DbConnectionPool.freeConnection=20
2005-11-18 11:47:38,828 DEBUG [Thread-12]: <=3D =
DbConnectionPool.freeConnection=20
2005-11-18 11:47:38,828 DEBUG [Thread-12]: <=3D Transaction.commit=20
2005-11-18 11:47:38,828 DEBUG [Thread-12]: <=3D =
MessageServiceHandler.onMessage=20
2005-11-18 11:47:47,452 INFO [http-8080-Processor25]: Process command: =
Get message (32)=20
2005-11-18 11:47:47,452 DEBUG [http-8080-Processor25]: =3D> =
MessageServiceHandler.processCommand=20
2005-11-18 11:47:47,452 DEBUG [http-8080-Processor25]: =3D> =
MessageServiceHandler.getMessage=20
2005-11-18 11:47:47,452 DEBUG [http-8080-Processor25]: =3D> =
MessageServiceHandler.getNextUndeliveredMessage=20
2005-11-18 11:47:47,452 DEBUG [http-8080-Processor25]: =3D> =
MessageServer.getUndeliveredMessages=20
2005-11-18 11:47:47,452 DEBUG [http-8080-Processor25]: =3D> =
DbConnectionPool.getConnection=20
2005-11-18 11:47:47,452 DEBUG [http-8080-Processor25]: <=3D =
DbConnectionPool.getConnection=20
2005-11-18 11:47:47,462 DEBUG [http-8080-Processor25]: <=3D =
MessageServer.getUndeliveredMessages=20
2005-11-18 11:47:47,462 DEBUG [http-8080-Processor25]: =3D> =
MessageServer.setFileDeliveryStatus=20
2005-11-18 11:47:47,462 DEBUG [http-8080-Processor25]: <=3D =
MessageServer.setFileDeliveryStatus=20
2005-11-18 11:47:47,462 DEBUG [http-8080-Processor25]: =3D> =
MessageServer.getMessageFromDataSource=20
2005-11-18 11:47:47,533 DEBUG [http-8080-Processor25]: <=3D =
MessageServiceHandler.getNextUndeliveredMessage with messageId: =
20051118-104728257-PO.po.PO.2@192.168.1.27 =
<http://service.com/%3Ca%20href=3D> " =
target=3D"_new">http://service.com/po.PO.2@192.168.1.27 =
<mailto:po.PO.2@192.168.1.27%3C/a> >=20
2005-11-18 11:47:47,533 DEBUG [http-8080-Processor25]: =3D> =
Transaction.commit (txID: #8)=20
2005-11-18 11:47:47,533 DEBUG [http-8080-Processor25]: =3D> =
DbConnectionPool.freeConnection=20
2005-11-18 11:47:47,533 DEBUG [http-8080-Processor25]: <=3D =
DbConnectionPool.freeConnection=20
2005-11-18 11:47:47,533 DEBUG [http-8080-Processor25]: <=3D =
Transaction.commit=20
2005-11-18 11:47:47,543 DEBUG [http-8080-Processor25]: <=3D =
MessageServiceHandler.getMessage=20
2005-11-18 11:47:47,543 DEBUG [http-8080-Processor25]: <=3D =
MessageServiceHandler.processCommand=20



---
Arnstein Vestad - PMP, CEH
Seniorr=E5dgiver, KITH - http://www.kith.no/
Tlf.: 73 55 10 35, mob.: 93 43 50 95
arn...@ki...