0 Replies Latest reply on May 30, 2005 5:27 AM by Sriram Padmanabhan

    MDB invoked multiple times

    Sriram Padmanabhan Newbie

      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