We have JBoss4.0.0 installed with Oracle as database. But for JMS persistance and state management we are using the HSQLDB. Now I have a MDB which has subscribed to a specific topic. From a Servlet we are invoking a Session Bean which in turn publishes a message to the topic to which the MDB listens. Now sometimes for a single publish of the message the MDB is invoked thrice. One more strange thing is this error is not reproducable.I am pasting the log for this specific instance. Please help me out . Vauable suggestions are welcome. The socket exception is because I am not closing the JMS connection. But that should be the cause of the above mentioned problem is it not?


2005-05-27 12:19:58,116 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 450000

2005-05-27 12:23:25,989 INFO [STDOUT] ---<In the servlet>--

2005-05-27 12:23:25,990 INFO [STDOUT] reportId POERP_01

2005-05-27 12:23:25,990 INFO [STDOUT] criterion <REPORT_ID>POERP_01</REPORT_ID><NO_OF_DAYS>1</NO_OF_DAYS><REP_ORG_ID>3930</REP_ORG_ID><PAYMENT_INFO></PAYMENT_INFO><OUTPUT_DIR></OUTPUT_DIR><OUT_FILE_NAME></OUT_FILE_NAME><DATE_FORMAT>YYYYMMDD</DATE_FORMAT><FROM_DATE></FROM_DATE><TO_DATE></TO_DATE>1<ROLLING_REPORT_FLAG>Y</ROLLING_REPORT_FLAG>

2005-05-27 12:23:25,990 INFO [STDOUT] queryinfo <REPORT_FORMAT>PSV</REPORT_FORMAT>

2005-05-27 12:23:25,990 INFO [STDOUT] ---</In the servlet>--

2005-05-27 12:23:26,023 INFO [DEFLogger] [com.ketera.dataextraction.helper.DEFvalidateReportParam]Validating the mandatory report parameters

2005-05-27 12:23:26,062 DEBUG [org.jboss.mq.referenceable.SpyConnectionFactoryObjectFactory] Extracting SpyConnectionFactory from reference

2005-05-27 12:23:26,064 DEBUG [org.jboss.mq.referenceable.SpyConnectionFactoryObjectFactory] The GenericConnectionFactory is: GenericConnectionFactory[server=org.jboss.mq.il.uil2.UILServerIL@1d36e0a connectionProperties={UIL_ADDRESS_KEY=10.200.91.22, UIL_CHUNKSIZE_KEY=1000000, UIL_TCPNODELAY_KEY=yes, ClientILService=org.jboss.mq.il.uil2.UILClientILService, UIL_PORT_KEY=8193, UIL_BUFFERSIZE_KEY=2048, PingPeriod=60000}]

2005-05-27 12:23:26,065 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin ReadTask.run

2005-05-27 12:23:26,065 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin ReadTask.run

2005-05-27 12:23:26,065 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin WriteTask.run

2005-05-27 12:23:26,065 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin WriteTask.run

2005-05-27 12:23:26,066 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectOutputStream

2005-05-27 12:23:26,066 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectOutputStream

2005-05-27 12:23:26,066 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectInputStream

2005-05-27 12:23:26,067 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectInputStream

2005-05-27 12:23:26,067 DEBUG [org.jboss.mq.il.uil2.UILClientILService] Starting

2005-05-27 12:23:26,068 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Setting up the UILClientIL Connection

2005-05-27 12:23:26,068 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] The UILClientIL Connection is set up

2005-05-27 12:23:26,071 DEBUG [org.jboss.mq.referenceable.SpyDestinationObjectFactory] SpyDestinationObjectFactory->getObjectInstance()

2005-05-27 12:23:26,074 INFO [DEFLogger] [com.ketera.dataextraction.util.DEFEmail]mail with the content {The Report Generation Request has been initiated with reference number 05052718} is being sent

2005-05-27 12:23:26,074 DEBUG [com.ketera.platform.email.EmailJMSUtil] Queue name is queue/emailQueue

2005-05-27 12:23:26,074 DEBUG [org.jboss.mq.referenceable.SpyConnectionFactoryObjectFactory] Extracting SpyConnectionFactory from reference

2005-05-27 12:23:26,075 INFO [DEFLogger] [com.ketera.dataextraction.common.DEFReportMDBBean]Mesasge driven Bean(MDB) has received the message with reportClassName-->PoERPHelper

2005-05-27 12:23:26,076 DEBUG [org.jboss.mq.referenceable.SpyConnectionFactoryObjectFactory] The GenericConnectionFactory is: GenericConnectionFactory[server=org.jboss.mq.il.uil2.UILServerIL@65e584 connectionProperties={UIL_ADDRESS_KEY=10.200.91.22, UIL_CHUNKSIZE_KEY=1000000, UIL_TCPNODELAY_KEY=yes, ClientILService=org.jboss.mq.il.uil2.UILClientILService, UIL_PORT_KEY=8193, UIL_BUFFERSIZE_KEY=2048, PingPeriod=60000}]

2005-05-27 12:23:26,076 DEBUG [org.jboss.mq.referenceable.SpyDestinationObjectFactory] SpyDestinationObjectFactory->getObjectInstance()

2005-05-27 12:23:26,077 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin ReadTask.run

2005-05-27 12:23:26,094 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin WriteTask.run

2005-05-27 12:23:26,094 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin ReadTask.run

2005-05-27 12:23:26,094 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin WriteTask.run

2005-05-27 12:23:26,094 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectOutputStream

2005-05-27 12:23:26,094 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectOutputStream

2005-05-27 12:23:26,095 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectInputStream

2005-05-27 12:23:26,096 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectInputStream

2005-05-27 12:23:26,096 DEBUG [org.jboss.mq.il.uil2.UILClientILService] Starting

2005-05-27 12:23:26,097 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Setting up the UILClientIL Connection

2005-05-27 12:23:26,098 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] The UILClientIL Connection is set up

2005-05-27 12:23:26,121 DEBUG [org.jboss.mq.il.uil2.UILClientILService] Stopping

2005-05-27 12:23:26,121 DEBUG [com.ketera.platform.email.EmailJMSUtil] Messase sent successfully. Message: com.ketera.platform.email.EmailData@141f0d7[to-addr-list={npreethi@ketera.com,rprasad@ketera.com},template-name=,body-text=The Report Generation Request has been initiated with reference number 05052718,.... ]

2005-05-27 12:23:26,121 DEBUG [org.jboss.mq.il.uil2.SocketManager] End ReadTask.run

2005-05-27 12:23:26,122 DEBUG [org.jboss.mq.il.uil2.SocketManager] End WriteTask.run

2005-05-27 12:23:26,122 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Exiting on IOE

java.io.EOFException

at java.io.ObjectInputStream$BlockDataInputStream.readByte(ObjectInputStream.java:2603)

at java.io.ObjectInputStream.readByte(ObjectInputStream.java:845)

at org.jboss.mq.il.uil2.SocketManager$ReadTask.run(SocketManager.java:279)

at java.lang.Thread.run(Thread.java:534)

2005-05-27 12:23:26,123 DEBUG [org.jboss.mq.il.uil2.SocketManager] End ReadTask.run

2005-05-27 12:23:26,123 DEBUG [org.jboss.mq.il.uil2.SocketManager] End WriteTask.run

2005-05-27 12:23:26,294 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]The Reflection code is called

2005-05-27 12:23:28,587 INFO [com.ketera.platform.email.ejb.DefaultEmailSenderImpl] Mail sent successfully. EmailData = com.ketera.platform.email.EmailData@11db478[to-addr-list={npreethi@ketera.com,rprasad@ketera.com},template-name=,body-text=The Report Generation Request has been initiated with reference number 05052718,.... ]

2005-05-27 12:23:28,588 INFO [com.ketera.platform.email.ejb.EmailMDBean] EMAIL_SEND_SUCCESScom.ketera.platform.email.EmailData@11db478[to-addr-list={npreethi@ketera.com,rprasad@ketera.com},template-name=,body-text=The Report Generation Request has been initiated with reference number 05052718,.... ]

2005-05-27 12:27:28,126 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 450000

2005-05-27 12:28:26,414 WARN [org.jboss.tm.TransactionImpl] Transaction TransactionImpl:XidImpl[FormatId=257, GlobalId=taurus/223, BranchQual=, localId=223] timed out. status=STATUS_ACTIVE

2005-05-27 12:28:29,313 INFO [DEFLogger] [com.ketera.dataextraction.report.PoERPHelper] PO_ERP in XML format is generated

2005-05-27 12:28:29,504 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]got Data XSL

2005-05-27 12:28:29,780 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]Customer XML is generated

2005-05-27 12:28:29,802 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]got format XSL

2005-05-27 12:28:30,301 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]outputDocument generated successfully

2005-05-27 12:28:30,876 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]DEF_REPORT_TRANSACTON table is updated successfully

2005-05-27 12:28:31,010 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender]Inside the Constructor TxnHubDocumentSender

2005-05-27 12:28:31,010 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender] the trxhub url is http://10.200.91.29:8180/trx/processDocument

2005-05-27 12:28:31,011 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender]Inside the sendDocument method

2005-05-27 12:28:31,028 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender]Connected to the trx hub

2005-05-27 12:28:31,029 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender]the output document is Purchase Order

2005-05-27 12:28:31,497 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender]the post parameters are senderName=DEF&document=Header%7CNumber+of+Detail+Records%3A1%7CTotal+of+Detail+Records%3A.48%7CRun+Date%3A20050527%7CFrom+Date%3A20050526%7CTo+Date%3A20050527%0AUser+Name%7CPurchase+Order+Number%7CSupplier+Name%7CSplit+Accounting+Type%7CSplit+Accounting+Charges%7CItem+Part+Number%7CItem+Description%7CSpecial+Request+Item%7CAdditional+Supplier+Item%7CPurchase+Order+Date%7CItem+Quantity%7CUnit+of+Measure%7CUnit+Price%7CExtended+Price%7CManufacturer+Part+Number%7CCurrency%7CShipping+Method%7CShipping+Carrier+Account+Number%7COrder+Comments+%28External%29%7COrder+Title%7CBusiness+Unit%7CBill+To+Street+1%7CBill+To+Street+2%7CBill+To+City%7CBill+To+State%7CBill+To+ZIP%7CBill+To+Attention%7CBill+To+Telephone+Number%7CBill+To+Fax+Number%7CBill+To+email+Address%7CBill+To+Mailstop%7CShip+To+Street+1%7CShip+To+Street+2%7CShip+To+City%7CShip+To+State%7CShip+To+ZIP%7CShip+To+Country%7CShip+To+Attention+Name%7CShip+To+Telephone+Number%7CShip+To+Fax+Number%7CShip+To+email+Address%7CShip+To+Mailstop%7CShip+To+Department+Location%7CShip+To+Address+ID%7CInternal+Line+Item+Comments%7CExternal+Line+Item+Comments%7CPayment+Info%7CVendor+Code%7CShip+to+Location+Name%7CUserID%7CCategory%7CPO+Line+Item%7CProduct+Group%7CLead+Time%7CManufacturer%7CKetera+Line+ID%7CRush+Order%7CRequested+By+Date%7CPurchasing+Review+Needed%7CHeader+Attachment+Count%7CLine+Attachment+Count%7CCreate+Date%7CTaxable%7CTaxable+Comments%7CComments+EntireOrder%7CShipping+Instructions%7COrder+on+Behalf%7CPreparer+Name%7CBPO+Number%7COrder+Type%7CBPO+Spend+Limit%7CBPOPaymentsOnly%7CBill+to+Location+Name%0ATEST+APPROVER+1%7CAX464735-1%7CCNF+Warehouse%7CPERCENTAGE%7C100%3AXCW-+Coldwater%2FFremont%2F00000000%3A.48%3B%7C70738-Q0%7CTRAINING+ROSTER+-+ASCEND+PROJECT+5-1%2F2+X+8-1%2F2%7CN%7CN%7C20050527%7C4%7CEA%7C.12%7C.48%7C70738-Q0%7CUSD%7CGround%7CNot+Applicable%7C%7CTHIS+is+for+CNF%7CCNF+Contract+Services%7CP.O.+Box+4673%7C%7CPortland%7COR%7C97208-4673%7CCNF+Contract+Services%7C5551212%7C14087489674%7CHighfour%40marketmile.com%7C%7C6755+North+Old+27%7C%7CFreemont%7CIN%7C46737%7CUS%7CTEST+APPROVER+1%7C5551212%7C14087489674%7Cqaperf%40marketmile.com%7C%7C%7CCN0708%7C%7C%7CAmerican+Express+-+American+Express+CPC%7C%7CXCW%7CEPSTESTAPPROVE1%7C14111800%7C1%7C%7C%7C%7C514360%7CN%7C%7CN%7C0%7C0%7C20050527%7CY%7C%7C%7C%7CN%7CTEST+APPROVER+1%7C%7Cregular%7C%7CN%7CEPS+Billing+Address%0A&receiverName=3930&correlationId=05052718&type=Purchase+Order%3APurchase+Order

2005-05-27 12:28:31,557 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender] Got the output Stream

2005-05-27 12:28:31,561 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender] Request send to txn hub

2005-05-27 12:28:31,845 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender] got the response from txn hub

2005-05-27 12:28:31,855 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender] <?xml version="1.0" encoding="ISO-8859-1"?>

2005-05-27 12:28:31,856 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender] 0: OK8a48db9d03e9529201041cfb1e1c06c9

2005-05-27 12:28:31,857 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]Externalization is done successfully

2005-05-27 12:28:31,860 INFO [DEFLogger] [com.ketera.dataextraction.util.DEFEmail]mail with the content {Report Generation success for Report reference Number 05052718} is being sent

2005-05-27 12:28:31,861 DEBUG [com.ketera.platform.email.EmailJMSUtil] Queue name is queue/emailQueue

2005-05-27 12:28:31,861 DEBUG [org.jboss.mq.referenceable.SpyConnectionFactoryObjectFactory] Extracting SpyConnectionFactory from reference

2005-05-27 12:28:31,864 DEBUG [org.jboss.mq.referenceable.SpyConnectionFactoryObjectFactory] The GenericConnectionFactory is: GenericConnectionFactory[server=org.jboss.mq.il.uil2.UILServerIL@781b93 connectionProperties={UIL_ADDRESS_KEY=10.200.91.22, UIL_CHUNKSIZE_KEY=1000000, UIL_TCPNODELAY_KEY=yes, ClientILService=org.jboss.mq.il.uil2.UILClientILService, UIL_PORT_KEY=8193, UIL_BUFFERSIZE_KEY=2048, PingPeriod=60000}]

2005-05-27 12:28:31,865 DEBUG [org.jboss.mq.referenceable.SpyDestinationObjectFactory] SpyDestinationObjectFactory->getObjectInstance()

2005-05-27 12:28:31,866 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin ReadTask.run

2005-05-27 12:28:31,866 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin ReadTask.run

2005-05-27 12:28:31,867 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin WriteTask.run

2005-05-27 12:28:31,867 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin WriteTask.run

2005-05-27 12:28:31,867 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectOutputStream

2005-05-27 12:28:31,868 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectOutputStream

2005-05-27 12:28:31,868 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectInputStream

2005-05-27 12:28:31,892 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectInputStream

2005-05-27 12:28:31,892 DEBUG [org.jboss.mq.il.uil2.UILClientILService] Starting

2005-05-27 12:28:31,893 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Setting up the UILClientIL Connection

2005-05-27 12:28:31,894 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] The UILClientIL Connection is set up

2005-05-27 12:28:31,938 DEBUG [org.jboss.mq.il.uil2.UILClientILService] Stopping

2005-05-27 12:28:31,938 DEBUG [org.jboss.mq.il.uil2.SocketManager] End WriteTask.run

2005-05-27 12:28:31,938 DEBUG [org.jboss.mq.il.uil2.SocketManager] End WriteTask.run

2005-05-27 12:28:31,946 DEBUG [org.jboss.mq.il.uil2.SocketManager] End ReadTask.run

2005-05-27 12:28:31,946 DEBUG [com.ketera.platform.email.EmailJMSUtil] Messase sent successfully. Message: com.ketera.platform.email.EmailData@2f7d55[to-addr-list={npreethi@ketera.com,rprasad@ketera.com},template-name=,body-text=Report Generation success for Report reference Number 05052718,.... ]

2005-05-27 12:28:31,939 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Exiting on IOE

java.net.SocketException: Socket closed

at java.net.SocketInputStream.read(SocketInputStream.java:162)

at java.io.BufferedInputStream.fill(BufferedInputStream.java:183)

at java.io.BufferedInputStream.read(BufferedInputStream.java:201)

at org.jboss.util.stream.NotifyingBufferedInputStream.read(NotifyingBufferedInputStream.java:67)

at java.io.ObjectInputStream$PeekInputStream.peek(ObjectInputStream.java:2133)

at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(ObjectInputStream.java:2313)

at java.io.ObjectInputStream$BlockDataInputStream.refill(ObjectInputStream.java:2380)

at java.io.ObjectInputStream$BlockDataInputStream.read(ObjectInputStream.java:2452)

at java.io.ObjectInputStream$BlockDataInputStream.readByte(ObjectInputStream.java:2601)

at java.io.ObjectInputStream.readByte(ObjectInputStream.java:845)

at org.jboss.mq.il.uil2.SocketManager$ReadTask.run(SocketManager.java:279)

at java.lang.Thread.run(Thread.java:534)

2005-05-27 12:28:31,965 DEBUG [org.jboss.mq.il.uil2.SocketManager] End ReadTask.run

2005-05-27 12:28:32,013 INFO [DEFLogger] [com.ketera.dataextraction.common.DEFReportMDBBean]Mesasge driven Bean(MDB) has received the message with reportClassName-->PoERPHelper

2005-05-27 12:28:32,383 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]The Reflection code is called

2005-05-27 12:28:34,641 INFO [com.ketera.platform.email.ejb.DefaultEmailSenderImpl] Mail sent successfully. EmailData = com.ketera.platform.email.EmailData@1badade[to-addr-list={npreethi@ketera.com,rprasad@ketera.com},template-name=,body-text=Report Generation success for Report reference Number 05052718,.... ]

2005-05-27 12:28:34,642 INFO [com.ketera.platform.email.ejb.EmailMDBean] EMAIL_SEND_SUCCESScom.ketera.platform.email.EmailData@1badade[to-addr-list={npreethi@ketera.com,rprasad@ketera.com},template-name=,body-text=Report Generation success for Report reference Number 05052718,.... ]

2005-05-27 12:33:32,026 WARN [org.jboss.tm.TransactionImpl] Transaction TransactionImpl:XidImpl[FormatId=257, GlobalId=taurus/232, BranchQual=, localId=232] timed out. status=STATUS_ACTIVE

2005-05-27 12:34:28,523 INFO [DEFLogger] [com.ketera.dataextraction.report.PoERPHelper] PO_ERP in XML format is generated

2005-05-27 12:34:28,528 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]got Data XSL

2005-05-27 12:34:28,576 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]Customer XML is generated

2005-05-27 12:34:28,579 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]got format XSL

2005-05-27 12:34:28,603 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]outputDocument generated successfully

2005-05-27 12:34:28,800 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]DEF_REPORT_TRANSACTON table is updated successfully

2005-05-27 12:34:28,816 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender]Inside the Constructor TxnHubDocumentSender

2005-05-27 12:34:28,817 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender] the trxhub url is http://10.200.91.29:8180/trx/processDocument

2005-05-27 12:34:28,817 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender]Inside the sendDocument method

2005-05-27 12:34:28,817 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender]Connected to the trx hub

2005-05-27 12:34:28,817 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender]the output document is Purchase Order

2005-05-27 12:34:28,822 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender]the post parameters are senderName=DEF&document=Header%7CNumber+of+Detail+Records%3A1%7CTotal+of+Detail+Records%3A.48%7CRun+Date%3A20050527%7CFrom+Date%3A20050526%7CTo+Date%3A20050527%0AUser+Name%7CPurchase+Order+Number%7CSupplier+Name%7CSplit+Accounting+Type%7CSplit+Accounting+Charges%7CItem+Part+Number%7CItem+Description%7CSpecial+Request+Item%7CAdditional+Supplier+Item%7CPurchase+Order+Date%7CItem+Quantity%7CUnit+of+Measure%7CUnit+Price%7CExtended+Price%7CManufacturer+Part+Number%7CCurrency%7CShipping+Method%7CShipping+Carrier+Account+Number%7COrder+Comments+%28External%29%7COrder+Title%7CBusiness+Unit%7CBill+To+Street+1%7CBill+To+Street+2%7CBill+To+City%7CBill+To+State%7CBill+To+ZIP%7CBill+To+Attention%7CBill+To+Telephone+Number%7CBill+To+Fax+Number%7CBill+To+email+Address%7CBill+To+Mailstop%7CShip+To+Street+1%7CShip+To+Street+2%7CShip+To+City%7CShip+To+State%7CShip+To+ZIP%7CShip+To+Country%7CShip+To+Attention+Name%7CShip+To+Telephone+Number%7CShip+To+Fax+Number%7CShip+To+email+Address%7CShip+To+Mailstop%7CShip+To+Department+Location%7CShip+To+Address+ID%7CInternal+Line+Item+Comments%7CExternal+Line+Item+Comments%7CPayment+Info%7CVendor+Code%7CShip+to+Location+Name%7CUserID%7CCategory%7CPO+Line+Item%7CProduct+Group%7CLead+Time%7CManufacturer%7CKetera+Line+ID%7CRush+Order%7CRequested+By+Date%7CPurchasing+Review+Needed%7CHeader+Attachment+Count%7CLine+Attachment+Count%7CCreate+Date%7CTaxable%7CTaxable+Comments%7CComments+EntireOrder%7CShipping+Instructions%7COrder+on+Behalf%7CPreparer+Name%7CBPO+Number%7COrder+Type%7CBPO+Spend+Limit%7CBPOPaymentsOnly%7CBill+to+Location+Name%0ATEST+APPROVER+1%7CAX464735-1%7CCNF+Warehouse%7CPERCENTAGE%7C100%3AXCW-+Coldwater%2FFremont%2F00000000%3A.48%3B%7C70738-Q0%7CTRAINING+ROSTER+-+ASCEND+PROJECT+5-1%2F2+X+8-1%2F2%7CN%7CN%7C20050527%7C4%7CEA%7C.12%7C.48%7C70738-Q0%7CUSD%7CGround%7CNot+Applicable%7C%7CTHIS+is+for+CNF%7CCNF+Contract+Services%7CP.O.+Box+4673%7C%7CPortland%7COR%7C97208-4673%7CCNF+Contract+Services%7C5551212%7C14087489674%7CHighfour%40marketmile.com%7C%7C6755+North+Old+27%7C%7CFreemont%7CIN%7C46737%7CUS%7CTEST+APPROVER+1%7C5551212%7C14087489674%7Cqaperf%40marketmile.com%7C%7C%7CCN0708%7C%7C%7CAmerican+Express+-+American+Express+CPC%7C%7CXCW%7CEPSTESTAPPROVE1%7C14111800%7C1%7C%7C%7C%7C514360%7CN%7C%7CN%7C0%7C0%7C20050527%7CY%7C%7C%7C%7CN%7CTEST+APPROVER+1%7C%7Cregular%7C%7CN%7CEPS+Billing+Address%0A&receiverName=3930&correlationId=05052718&type=Purchase+Order%3APurchase+Order

2005-05-27 12:34:28,824 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender] Got the output Stream

2005-05-27 12:34:28,824 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender] Request send to txn hub

2005-05-27 12:34:29,097 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender] got the response from txn hub

2005-05-27 12:34:29,104 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender] <?xml version="1.0" encoding="ISO-8859-1"?>

2005-05-27 12:34:29,105 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender] 0: OK8a48db9d03e9529201041d00921506ea

2005-05-27 12:34:29,105 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]Externalization is done successfully

2005-05-27 12:34:29,118 INFO [DEFLogger] [com.ketera.dataextraction.util.DEFEmail]mail with the content {Report Generation success for Report reference Number 05052718} is being sent

2005-05-27 12:34:29,118 DEBUG [com.ketera.platform.email.EmailJMSUtil] Queue name is queue/emailQueue

2005-05-27 12:34:29,118 DEBUG [org.jboss.mq.referenceable.SpyConnectionFactoryObjectFactory] Extracting SpyConnectionFactory from reference

2005-05-27 12:34:29,120 DEBUG [org.jboss.mq.referenceable.SpyConnectionFactoryObjectFactory] The GenericConnectionFactory is: GenericConnectionFactory[server=org.jboss.mq.il.uil2.UILServerIL@5c358b connectionProperties={UIL_ADDRESS_KEY=10.200.91.22, UIL_CHUNKSIZE_KEY=1000000, UIL_TCPNODELAY_KEY=yes, ClientILService=org.jboss.mq.il.uil2.UILClientILService, UIL_PORT_KEY=8193, UIL_BUFFERSIZE_KEY=2048, PingPeriod=60000}]

2005-05-27 12:34:29,120 DEBUG [org.jboss.mq.referenceable.SpyDestinationObjectFactory] SpyDestinationObjectFactory->getObjectInstance()

2005-05-27 12:34:29,134 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin ReadTask.run

2005-05-27 12:34:29,134 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin ReadTask.run

2005-05-27 12:34:29,135 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin WriteTask.run

2005-05-27 12:34:29,135 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectOutputStream

2005-05-27 12:34:29,135 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectInputStream

2005-05-27 12:34:29,145 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin WriteTask.run

2005-05-27 12:34:29,145 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectOutputStream

2005-05-27 12:34:29,169 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectInputStream

2005-05-27 12:34:29,169 DEBUG [org.jboss.mq.il.uil2.UILClientILService] Starting

2005-05-27 12:34:29,170 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Setting up the UILClientIL Connection

2005-05-27 12:34:29,171 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] The UILClientIL Connection is set up

2005-05-27 12:34:29,197 DEBUG [org.jboss.mq.il.uil2.SocketManager] End WriteTask.run

2005-05-27 12:34:29,197 DEBUG [org.jboss.mq.il.uil2.UILClientILService] Stopping

2005-05-27 12:34:29,198 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Exiting on IOE

java.net.SocketException: Socket closed

at java.net.SocketInputStream.read(SocketInputStream.java:162)

at java.io.BufferedInputStream.fill(BufferedInputStream.java:183)

at java.io.BufferedInputStream.read(BufferedInputStream.java:201)

at org.jboss.util.stream.NotifyingBufferedInputStream.read(NotifyingBufferedInputStream.java:67)

at java.io.ObjectInputStream$PeekInputStream.peek(ObjectInputStream.java:2133)

at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(ObjectInputStream.java:2313)

at java.io.ObjectInputStream$BlockDataInputStream.refill(ObjectInputStream.java:2380)

at java.io.ObjectInputStream$BlockDataInputStream.read(ObjectInputStream.java:2452)

at java.io.ObjectInputStream$BlockDataInputStream.readByte(ObjectInputStream.java:2601)

at java.io.ObjectInputStream.readByte(ObjectInputStream.java:845)

at org.jboss.mq.il.uil2.SocketManager$ReadTask.run(SocketManager.java:279)

at java.lang.Thread.run(Thread.java:534)

2005-05-27 12:34:29,198 DEBUG [org.jboss.mq.il.uil2.SocketManager] End ReadTask.run

2005-05-27 12:34:29,197 DEBUG [org.jboss.mq.il.uil2.SocketManager] End WriteTask.run

2005-05-27 12:34:29,198 DEBUG [com.ketera.platform.email.EmailJMSUtil] Messase sent successfully. Message: com.ketera.platform.email.EmailData@8f544b[to-addr-list={npreethi@ketera.com,rprasad@ketera.com},template-name=,body-text=Report Generation success for Report reference Number 05052718,.... ]

2005-05-27 12:34:29,206 DEBUG [org.jboss.mq.il.uil2.SocketManager] End ReadTask.run

2005-05-27 12:34:29,218 INFO [DEFLogger] [com.ketera.dataextraction.common.DEFReportMDBBean]Mesasge driven Bean(MDB) has received the message with reportClassName-->PoERPHelper

2005-05-27 12:34:29,379 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]The Reflection code is called

2005-05-27 12:34:31,639 INFO [com.ketera.platform.email.ejb.DefaultEmailSenderImpl] Mail sent successfully. EmailData = com.ketera.platform.email.EmailData@1e05a29[to-addr-list={npreethi@ketera.com,rprasad@ketera.com},template-name=,body-text=Report Generation success for Report reference Number 05052718,.... ]

2005-05-27 12:34:31,639 INFO [com.ketera.platform.email.ejb.EmailMDBean] EMAIL_SEND_SUCCESScom.ketera.platform.email.EmailData@1e05a29[to-addr-list={npreethi@ketera.com,rprasad@ketera.com},template-name=,body-text=Report Generation success for Report reference Number 05052718,.... ]

2005-05-27 12:34:58,156 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 450000

2005-05-27 12:36:21,543 INFO [DEFLogger] [com.ketera.dataextraction.report.PoERPHelper] PO_ERP in XML format is generated

2005-05-27 12:36:21,561 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]got Data XSL

2005-05-27 12:36:21,652 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]Customer XML is generated

2005-05-27 12:36:21,654 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]got format XSL

2005-05-27 12:36:21,703 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]outputDocument generated successfully

2005-05-27 12:36:21,742 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]DEF_REPORT_TRANSACTON table is updated successfully

2005-05-27 12:36:21,744 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender]Inside the Constructor TxnHubDocumentSender

2005-05-27 12:36:21,744 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender] the trxhub url is http://10.200.91.29:8180/trx/processDocument

2005-05-27 12:36:21,744 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender]Inside the sendDocument method

2005-05-27 12:36:21,744 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender]Connected to the trx hub

2005-05-27 12:36:21,744 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender]the output document is Purchase Order

2005-05-27 12:36:21,819 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender]the post parameters are senderName=DEF&document=Header%7CNumber+of+Detail+Records%3A1%7CTotal+of+Detail+Records%3A.48%7CRun+Date%3A20050527%7CFrom+Date%3A20050526%7CTo+Date%3A20050527%0AUser+Name%7CPurchase+Order+Number%7CSupplier+Name%7CSplit+Accounting+Type%7CSplit+Accounting+Charges%7CItem+Part+Number%7CItem+Description%7CSpecial+Request+Item%7CAdditional+Supplier+Item%7CPurchase+Order+Date%7CItem+Quantity%7CUnit+of+Measure%7CUnit+Price%7CExtended+Price%7CManufacturer+Part+Number%7CCurrency%7CShipping+Method%7CShipping+Carrier+Account+Number%7COrder+Comments+%28External%29%7COrder+Title%7CBusiness+Unit%7CBill+To+Street+1%7CBill+To+Street+2%7CBill+To+City%7CBill+To+State%7CBill+To+ZIP%7CBill+To+Attention%7CBill+To+Telephone+Number%7CBill+To+Fax+Number%7CBill+To+email+Address%7CBill+To+Mailstop%7CShip+To+Street+1%7CShip+To+Street+2%7CShip+To+City%7CShip+To+State%7CShip+To+ZIP%7CShip+To+Country%7CShip+To+Attention+Name%7CShip+To+Telephone+Number%7CShip+To+Fax+Number%7CShip+To+email+Address%7CShip+To+Mailstop%7CShip+To+Department+Location%7CShip+To+Address+ID%7CInternal+Line+Item+Comments%7CExternal+Line+Item+Comments%7CPayment+Info%7CVendor+Code%7CShip+to+Location+Name%7CUserID%7CCategory%7CPO+Line+Item%7CProduct+Group%7CLead+Time%7CManufacturer%7CKetera+Line+ID%7CRush+Order%7CRequested+By+Date%7CPurchasing+Review+Needed%7CHeader+Attachment+Count%7CLine+Attachment+Count%7CCreate+Date%7CTaxable%7CTaxable+Comments%7CComments+EntireOrder%7CShipping+Instructions%7COrder+on+Behalf%7CPreparer+Name%7CBPO+Number%7COrder+Type%7CBPO+Spend+Limit%7CBPOPaymentsOnly%7CBill+to+Location+Name%0ATEST+APPROVER+1%7CAX464735-1%7CCNF+Warehouse%7CPERCENTAGE%7C100%3AXCW-+Coldwater%2FFremont%2F00000000%3A.48%3B%7C70738-Q0%7CTRAINING+ROSTER+-+ASCEND+PROJECT+5-1%2F2+X+8-1%2F2%7CN%7CN%7C20050527%7C4%7CEA%7C.12%7C.48%7C70738-Q0%7CUSD%7CGround%7CNot+Applicable%7C%7CTHIS+is+for+CNF%7CCNF+Contract+Services%7CP.O.+Box+4673%7C%7CPortland%7COR%7C97208-4673%7CCNF+Contract+Services%7C5551212%7C14087489674%7CHighfour%40marketmile.com%7C%7C6755+North+Old+27%7C%7CFreemont%7CIN%7C46737%7CUS%7CTEST+APPROVER+1%7C5551212%7C14087489674%7Cqaperf%40marketmile.com%7C%7C%7CCN0708%7C%7C%7CAmerican+Express+-+American+Express+CPC%7C%7CXCW%7CEPSTESTAPPROVE1%7C14111800%7C1%7C%7C%7C%7C514360%7CN%7C%7CN%7C0%7C0%7C20050527%7CY%7C%7C%7C%7CN%7CTEST+APPROVER+1%7C%7Cregular%7C%7CN%7CEPS+Billing+Address%0A&receiverName=3930&correlationId=05052718&type=Purchase+Order%3APurchase+Order

2005-05-27 12:36:21,824 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender] Got the output Stream

2005-05-27 12:36:21,824 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender] Request send to txn hub

2005-05-27 12:36:22,325 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender] got the response from txn hub

2005-05-27 12:36:22,327 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender] <?xml version="1.0" encoding="ISO-8859-1"?>

2005-05-27 12:36:22,327 INFO [DEFLogger] [com.ketera.dataextraction.transport.TxnHubDocumentSender] 0: OK8a48db9d03e9529201041d024c9f070b

2005-05-27 12:36:22,328 INFO [DEFLogger] [com.ketera.dataextraction.controller.ReportController]Externalization is done successfully

2005-05-27 12:36:22,330 INFO [DEFLogger] [com.ketera.dataextraction.util.DEFEmail]mail with the content {Report Generation success for Report reference Number 05052718} is being sent

2005-05-27 12:36:22,331 DEBUG [com.ketera.platform.email.EmailJMSUtil] Queue name is queue/emailQueue

2005-05-27 12:36:22,331 DEBUG [org.jboss.mq.referenceable.SpyConnectionFactoryObjectFactory] Extracting SpyConnectionFactory from reference

2005-05-27 12:36:22,352 DEBUG [org.jboss.mq.referenceable.SpyConnectionFactoryObjectFactory] The GenericConnectionFactory is: GenericConnectionFactory[server=org.jboss.mq.il.uil2.UILServerIL@242ba3 connectionProperties={UIL_ADDRESS_KEY=10.200.91.22, UIL_CHUNKSIZE_KEY=1000000, UIL_TCPNODELAY_KEY=yes, ClientILService=org.jboss.mq.il.uil2.UILClientILService, UIL_PORT_KEY=8193, UIL_BUFFERSIZE_KEY=2048, PingPeriod=60000}]

2005-05-27 12:36:22,353 DEBUG [org.jboss.mq.referenceable.SpyDestinationObjectFactory] SpyDestinationObjectFactory->getObjectInstance()

2005-05-27 12:36:22,354 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin ReadTask.run

2005-05-27 12:36:22,355 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin WriteTask.run

2005-05-27 12:36:22,355 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectOutputStream

2005-05-27 12:36:22,356 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin ReadTask.run

2005-05-27 12:36:22,356 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectInputStream

2005-05-27 12:36:22,356 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin WriteTask.run

2005-05-27 12:36:22,356 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectOutputStream

2005-05-27 12:36:22,357 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectInputStream

2005-05-27 12:36:22,358 DEBUG [org.jboss.mq.il.uil2.UILClientILService] Starting

2005-05-27 12:36:22,359 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Setting up the UILClientIL Connection

2005-05-27 12:36:22,359 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] The UILClientIL Connection is set up

2005-05-27 12:36:22,367 DEBUG [org.jboss.mq.il.uil2.UILClientILService] Stopping

2005-05-27 12:36:22,368 DEBUG [org.jboss.mq.il.uil2.SocketManager] End ReadTask.run

2005-05-27 12:36:22,368 DEBUG [com.ketera.platform.email.EmailJMSUtil] Messase sent successfully. Message: com.ketera.platform.email.EmailData@18806c4[to-addr-list={npreethi@ketera.com,rprasad@ketera.com},template-name=,body-text=Report Generation success for Report reference Number 05052718,.... ]

2005-05-27 12:36:22,368 DEBUG [org.jboss.mq.il.uil2.SocketManager] End WriteTask.run

2005-05-27 12:36:22,369 DEBUG [org.jboss.mq.il.uil2.SocketManager] End WriteTask.run

2005-05-27 12:36:22,369 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Exiting on IOE

java.io.EOFException

at java.io.ObjectInputStream$BlockDataInputStream.readByte(ObjectInputStream.java:2603)

at java.io.ObjectInputStream.readByte(ObjectInputStream.java:845)

at org.jboss.mq.il.uil2.SocketManager$ReadTask.run(SocketManager.java:279)

at java.lang.Thread.run(Thread.java:534)

2005-05-27 12:36:22,370 DEBUG [org.jboss.mq.il.uil2.SocketManager] End ReadTask.run

2005-05-27 12:36:24,804 INFO [com.ketera.platform.email.ejb.DefaultEmailSenderImpl] Mail sent successfully. EmailData = com.ketera.platform.email.EmailData@eadf59[to-addr-list={npreethi@ketera.com,rprasad@ketera.com},template-name=,body-text=Report Generation success for Report reference Number 05052718,.... ]

2005-05-27 12:36:24,805 INFO [com.ketera.platform.email.ejb.EmailMDBean] EMAIL_SEND_SUCCESScom.ketera.platform.email.EmailData@eadf59[to-addr-list={npreethi@ketera.com,rprasad@ketera.com},template-name=,body-text=Report Generation success for Report reference Number 05052718,.... ]

2005-05-27 12:42:28,166 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 450000