MDB works only after restarting the app
Savotchkin Egor
I configured Embedded MQ with Studio 5.1.1 for my application. There exists
queue jms/InTransport_Q that is listened by MDB ExchangeListener. The
problem is that after starting the server the MDB onMessage method does not
get invoked when I put a message in the queue and after several seconds the
error is displayed:
"[27.01.04 18:09:03:067 MSK] 5b36bab2 JMSExceptionL E WMSG0018E: Error on
JMSConnection for MDB ExchangeListener , JMSDestination jms/InTransport_Q :
javax.jms.JMSException: MQJMS1025: failed to browse message
at
com.ibm.mq.jms.services.ConfigEnvironment.newException(ConfigEnvironment.jav
a:540)
at
com.ibm.mq.jms.MQQueueAgentThread1Impl.browse(MQQueueAgentThread1Impl.java:3
24)
at com.ibm.mq.jms.MQQueueAgentThread.run(MQQueueAgentThread.java:1553)
at java.lang.Thread.run(Thread.java:568)
---- Begin backtrace for Nested Throwables
com.ibm.mq.MQException: MQJE001: Completion Code 2, Reason 2026
at
com.ibm.mq.jms.MQQueueAgentThread1Impl.makeSuitableForDelivery(MQQueueAgentT
hread1Impl.java:583)
at
com.ibm.mq.jms.MQQueueAgentThread1Impl.browse(MQQueueAgentThread1Impl.java:2
51)
at com.ibm.mq.jms.MQQueueAgentThread.run(MQQueueAgentThread.java:1553)
at java.lang.Thread.run(Thread.java:568)
[27.01.04 18:09:03:098 MSK] 5b36bab2 JMSExceptionL E WMSG0057E: Error on
JMSConnection for MDB ExchangeListener , JMSDestination jms/InTransport_Q ,
JMS Linked Exception : com.ibm.mq.MQException: MQJE001: Completion Code 2,
Reason 2026
at
com.ibm.mq.jms.MQQueueAgentThread1Impl.makeSuitableForDelivery(MQQueueAgentT
hread1Impl.java:583)
at
com.ibm.mq.jms.MQQueueAgentThread1Impl.browse(MQQueueAgentThread1Impl.java:2
51)
at com.ibm.mq.jms.MQQueueAgentThread.run(MQQueueAgentThread.java:1553)
at java.lang.Thread.run(Thread.java:568)
[27.01.04 18:09:03:145 MSK] 5b36bab2 ConnectionEve A J2CA0056I: The
Connection Manager received a fatal connection error from the Resource
Adaptor for resource jms/Transport_QCF. The exception which was received is
javax.jms.JMSException: MQJMS1025: failed to browse message"
Strange thing is that after restarting the application (not the whole
server) the MDB works fine!!!! From the trace below you can see that the
onMessage method gets called (please, do not pay attention to the
exception).
Any ideas?
Egor.
*** Starting the server ***
************ Start Display Current Environment ************
WebSphere Platform 5.1 [BASE 5.1.0 b0344.02] running with process name
localhost\localhost\server1 and process id 3072
Host Operating System is Windows XP, version 5.1
Java version = J2RE 1.4.1 IBM Windows 32 build cn1411-20031011 (JIT enabled:
jitc), Java Compiler = jitc, Java VM name = Classic VM
was.install.root = D:\IBM\WebSphere Studio\Application
Developer\v5.1.1\runtimes\base_v51
user.install.root = D:\IBM\WebSphere Studio\Application
Developer\v5.1.1\runtimes\base_v51
Java Home = D:\IBM\WebSphere Studio\Application
Developer\v5.1.1\runtimes\base_v51\java\jre
ws.ext.dirs = D:\IBM\WebSphere Studio\Application
Developer\v5.1.1\runtimes\base_v51/java/lib;D:\IBM\WebSphere
Studio\Application
Developer\v5.1.1\runtimes\base_v51/classes;D:\IBM\WebSphere
Studio\Application
Developer\v5.1.1\runtimes\base_v51/classes;D:\IBM\WebSphere
Studio\Application Developer\v5.1.1\runtimes\base_v51/lib;D:\IBM\WebSphere
Studio\Application
Developer\v5.1.1\runtimes\base_v51/lib/ext;D:\IBM\WebSphere
Studio\Application
Developer\v5.1.1\runtimes\base_v51/web/help;D:\IBM\WebSphere
Studio\Application
Developer\v5.1.1\runtimes\base_v51/deploytool/itp/plugins/com.ibm.etools.ejb
deploy/runtime;D:/IBM/WebSphere Studio/Application
Developer/v5.1.1/wstools/eclipse/plugins/com.ibm.etools.webservice_5.1.1/run
time/worf.jar
Classpath = D:\IBM\WebSphere Studio\Application
Developer\v5.1.1\runtimes\base_v51/properties;D:\IBM\WebSphere
Studio\Application
Developer\v5.1.1\runtimes\base_v51/properties;D:\IBM\WebSphere
Studio\Application
Developer\v5.1.1\runtimes\base_v51/lib/bootstrap.jar;D:\IBM\WebSphere
Studio\Application
Developer\v5.1.1\runtimes\base_v51/lib/j2ee.jar;D:\IBM\WebSphere
Studio\Application
Developer\v5.1.1\runtimes\base_v51/lib/lmproxy.jar;D:\IBM\WebSphere
Studio\Application
Developer\v5.1.1\runtimes\base_v51/lib/urlprotocols.jar;C:\UTS\LOGS;C:\UTS\L
IB\stllogger.jar;C:\UTS\LIB\commons-logging.jar;C:\UTS\LIB\log4j-1.2.6.jar;C
:\UTS\LIB;D:/IBM/WebSphere Studio/Application
Developer/v5.1.1/wstools/eclipse/plugins/com.ibm.etools.websphere.tools.comm
on_5.1.1/runtime/wteServers.jar;D:/IBM/WebSphere Studio/Application
Developer/v5.1.1/wstools/eclipse/plugins/com.ibm.etools.websphere.tools.comm
on_5.1.1/runtime/wasToolsCommon.jar
Java Library path = D:\IBM\WebSphere Studio\Application
Developer\v5.1.1\runtimes\base_v51/bin;D:\IBM\WebSphere Studio\Application
Developer\v5.1.1\runtimes\base_v51/java/bin;D:\IBM\WebSphere
Studio\Application
Developer\v5.1.1\runtimes\base_v51/java/jre/bin;D:\IBM\WebSphere
Studio\Application Developer\v5.1.1\eclipse\jre\bin;.;D:\IBM\WebSphere
Studio\Application Developer\v5.1.1\eclipse\jre\bin;d:\IBM\WebSphere
MQ\Java\lib;d:\IBM\WebSphere Studio\Application
Developer\v5.1\runtimes\base_v5\bin;D:\IBM\WebSphere\AppServer\java\bin;D:\I
BM\WebSphere Studio\runtimes\base_v5\bin;D:/IBM/WebSphere
MQ/WEMPS\bin;d:\Program
Files\TeXLive\bin\win32;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\system32\W
BEM;d:\Program Files\netpbm\bin;d:\java\jdk1.3.0_02\bin;C:\Program
Files\Microsoft SQL
Server\80\Tools\BINN;D:\Pub\IBM\InterfaceTool\Bridge2Java\runtime\bridge2jav
a\Release;D:\IBM\WebSphere MQ\Java\bin;D:\IBM\WebSphere
MQ\bin;D:\IBM\WebSphere MQ\Java\bin;D:\IBM\WebSphere
MQ\WEMPS\bin;D:\IBM\WebSphere Studio\Application
Developer\v5.1.1\runtimes\base_v51\bin
************* End Display Current Environment *************
[27.01.04 18:07:15:796 MSK] 3c7c7ab0 ManagerAdmin I TRAS0017I: The startup
trace state is *=all=disabled.
[27.01.04 18:07:16:655 MSK] 3c7c7ab0 AdminInitiali A ADMN0015I: AdminService
initialized
[27.01.04 18:07:17:920 MSK] 3c7c7ab0 Configuration A SECJ0215I: Successfully
set JAAS login provider configuration class to
com.ibm.ws.security.auth.login.Configuration.
[27.01.04 18:07:17:967 MSK] 3c7c7ab0 SecurityDM I SECJ0231I: The Security
component's FFDC Diagnostic Module com.ibm.ws.security.core.SecurityDM
registered successfully: true.
[27.01.04 18:07:18:327 MSK] 3c7c7ab0 SecurityCompo I SECJ0309I: Java 2
Security is disabled.
[27.01.04 18:07:18:342 MSK] 3c7c7ab0 SecurityCompo I SECJ0212I: WCCM JAAS
configuration information successfully pushed to login provider class.
[27.01.04 18:07:18:374 MSK] 3c7c7ab0 SecurityCompo I SECJ0240I: Security
service initialization completed successfully
[27.01.04 18:07:20:014 MSK] 3c7c7ab0 SASRas A JSAS0001I: Security
configuration initialized.
[27.01.04 18:07:22:826 MSK] 3c7c7ab0 SASRas A JSAS0002I: Authentication
protocol: CSIV2/IBM
[27.01.04 18:07:22:826 MSK] 3c7c7ab0 SASRas A JSAS0003I: Authentication
mechanism: SWAM
[27.01.04 18:07:22:826 MSK] 3c7c7ab0 SASRas A JSAS0004I: Principal name:
localhost/wasadmin
[27.01.04 18:07:22:920 MSK] 3c7c7ab0 SASRas A JSAS0005I: SecurityCurrent
registered.
[27.01.04 18:07:22:936 MSK] 3c7c7ab0 SASRas A JSAS0006I: Security connection
interceptor initialized.
[27.01.04 18:07:22:951 MSK] 3c7c7ab0 SASRas A JSAS0007I: Client request
interceptor registered.
[27.01.04 18:07:23:107 MSK] 3c7c7ab0 SASRas A JSAS0008I: Server request
interceptor registered.
[27.01.04 18:07:23:154 MSK] 3c7c7ab0 SASRas A JSAS0009I: IOR interceptor
registered.
[27.01.04 18:07:24:670 MSK] 3c7c7ab0 JMSEmbeddedPr A MSGS0050I: Starting the
Queue Manager
[27.01.04 18:07:26:326 MSK] 3c7c7ab0 JMSEmbeddedPr A MSGS0051I: Queue
Manager open for business
[27.01.04 18:07:26:326 MSK] 3c7c7ab0 JMSEmbeddedPr A MSGS0052I: Starting the
Broker
[27.01.04 18:07:28:904 MSK] 3c7c7ab0 JMSEmbeddedPr A MSGS0053I: Broker open
for business
[27.01.04 18:07:29:607 MSK] 3c7c7ab0 ResourceMgrIm I WSVR0049I: Binding
InTransport_Q as jms/InTransport_Q
[27.01.04 18:07:29:654 MSK] 3c7c7ab0 ResourceMgrIm I WSVR0049I: Binding
OutTransport_Q as jms/OutTransport_Q
[27.01.04 18:07:29:919 MSK] 3c7c7ab0 ResourceMgrIm I WSVR0049I: Binding
Transport_QCF as jms/Transport_QCF
[27.01.04 18:07:30:076 MSK] 3c7c7ab0 ResourceMgrIm I WSVR0049I: Binding
TransportDS_CF as eis/jdbc/transportDS_CMP
[27.01.04 18:07:30:201 MSK] 3c7c7ab0 ResourceMgrIm I WSVR0049I: Binding
TransportDS as jdbc/transportDS
[27.01.04 18:07:30:826 MSK] 3c7c7ab0 CacheServiceI I DYNA0048I: WebSphere
Dynamic Cache initialized successfully.
[27.01.04 18:07:31:107 MSK] 3c7c7ab0 UserRegistryI A SECJ0136I: Custom
Registry:com.ibm.ws.security.registry.nt.NTLocalDomainRegistryImpl has been
initialized
[27.01.04 18:07:33:325 MSK] 3c7c7ab0 JMXSoapAdapte A ADMC0013I: SOAP
connector available at port 8880
[27.01.04 18:07:33:513 MSK] 3c7c7ab0 SecurityCompo I SECJ0243I: Security
service started successfully
[27.01.04 18:07:33:513 MSK] 3c7c7ab0 SecurityCompo I SECJ0210I: Security
enabled true
[27.01.04 18:07:37:012 MSK] 3c7c7ab0 ApplicationMg A WSVR0200I: Starting
application: UTS
[27.01.04 18:07:50:917 MSK] 3c7c7ab0 EJBContainerI I WSVR0207I: Preparing to
start EJB jar: ConfigEJB.jar
[27.01.04 18:07:51:199 MSK] 3c7c7ab0 EJBContainerI I WSVR0037I: Starting EJB
jar: ConfigEJB.jar
[27.01.04 18:07:52:214 MSK] 3c7c7ab0 EJBContainerI I WSVR0207I: Preparing to
start EJB jar: ExchangeEJB.jar
[27.01.04 18:07:52:417 MSK] 3c7c7ab0 EJBContainerI I WSVR0037I: Starting EJB
jar: ExchangeEJB.jar
[27.01.04 18:07:52:667 MSK] 3c7c7ab0 EJBContainerI I WSVR0207I: Preparing to
start EJB jar: JournalEJB.jar
[27.01.04 18:07:52:870 MSK] 3c7c7ab0 EJBContainerI I WSVR0037I: Starting EJB
jar: JournalEJB.jar
[27.01.04 18:07:53:011 MSK] 3c7c7ab0 EJBContainerI I WSVR0207I: Preparing to
start EJB jar: SequenceEJB.jar
[27.01.04 18:07:53:058 MSK] 3c7c7ab0 EJBContainerI I WSVR0037I: Starting EJB
jar: SequenceEJB.jar
[27.01.04 18:07:53:136 MSK] 3c7c7ab0 EJBContainerI I WSVR0207I: Preparing to
start EJB jar: TransportEJB.jar
[27.01.04 18:07:53:620 MSK] 3c7c7ab0 EJBContainerI I WSVR0037I: Starting EJB
jar: TransportEJB.jar
[27.01.04 18:07:54:011 MSK] 3c7c7ab0 WebContainer A SRVE0161I: IBM WebSphere
Application Server - Web Container. Copyright IBM Corp. 1998-2002
[27.01.04 18:07:54:026 MSK] 3c7c7ab0 WebContainer A SRVE0162I: Servlet
Specification Level: 2.3
[27.01.04 18:07:54:026 MSK] 3c7c7ab0 WebContainer A SRVE0163I: Supported JSP
Specification Level: 1.2
[27.01.04 18:07:54:183 MSK] 3c7c7ab0 WebContainer A SRVE0169I: Loading Web
Module: Transport Web Administration.
[27.01.04 18:07:54:995 MSK] 3c7c7ab0 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: JSP 1.2 Processor: init
[27.01.04 18:07:56:136 MSK] 3c7c7ab0 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: FormLoginServlet: init
[27.01.04 18:07:56:151 MSK] 3c7c7ab0 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: FormLogoutServlet: init
[27.01.04 18:07:56:151 MSK] 3c7c7ab0 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: SimpleFileServlet: init
[27.01.04 18:07:56:167 MSK] 3c7c7ab0 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: InvokerServlet: init
[27.01.04 18:07:56:198 MSK] 3c7c7ab0 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: SetupServlet: init
[27.01.04 18:07:56:854 MSK] 3c7c7ab0 WSRdbDataSour I DSRA8203I: Database
product name : Microsoft SQL Server
[27.01.04 18:07:56:854 MSK] 3c7c7ab0 WSRdbDataSour I DSRA8204I: Database
product version : Microsoft SQL Server 2000 - 8.00.760 (Intel X86)
Dec 17 2002 14:22:05
Copyright (c) 1988-2003 Microsoft Corporation
Developer Edition on Windows NT 5.1 (Build 2600: Service Pack 1)
[27.01.04 18:07:56:854 MSK] 3c7c7ab0 WSRdbDataSour I DSRA8205I: JDBC driver
name : SQLServer
[27.01.04 18:07:56:870 MSK] 3c7c7ab0 WSRdbDataSour I DSRA8206I: JDBC driver
version : 3.3.6 (008820.005005.006311)
[27.01.04 18:07:57:198 MSK] 3c7c7ab0 SystemOut O 18:07:57,198 INFO
TransportMgrBean.startModemTimer - Registering ModemConnectionListener,
check interval: 10000 ms
Timer service [WebSphere:name=timer1] with listener
[ru.lanit.uts.transport.util.ModemConnectionListener@2d31faa9], interval =
10000 has been created
[27.01.04 18:07:57:510 MSK] 3c7c7ab0 SystemOut O 18:07:57,510 INFO
TransportMgrBean.startOutboxDirectoryTimer - Registering DirectoryWhatcher,
check interval: 10000 ms
Timer service [WebSphere:name=timer2] with listener
[ru.lanit.uts.transport.util.DirectoryWhatcher@75c1fab4], interval = 10000
has been created
[27.01.04 18:07:57:651 MSK] 3c7c7ab0 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: action: init
[27.01.04 18:07:57:932 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:08:02:135 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:08:03:010 MSK] 3c7c7ab0 ApplicationMg A WSVR0221I: Application
started: UTS
[27.01.04 18:08:03:026 MSK] 3c7c7ab0 ApplicationMg A WSVR0200I: Starting
application: IBMUTC
[27.01.04 18:08:03:307 MSK] 3c7c7ab0 WebContainer A SRVE0169I: Loading Web
Module: IBM Universal Test Client.
[27.01.04 18:08:03:463 MSK] 3c7c7ab0 WebGroup I SRVE0180I: [IBM Universal
Test Client] [/UTC] [Servlet.LOG]: JSP 1.2 Processor: init
[27.01.04 18:08:03:479 MSK] 3c7c7ab0 WebGroup I SRVE0180I: [IBM Universal
Test Client] [/UTC] [Servlet.LOG]: FormLoginServlet: init
[27.01.04 18:08:03:479 MSK] 3c7c7ab0 WebGroup I SRVE0180I: [IBM Universal
Test Client] [/UTC] [Servlet.LOG]: FormLogoutServlet: init
[27.01.04 18:08:03:479 MSK] 3c7c7ab0 WebGroup I SRVE0180I: [IBM Universal
Test Client] [/UTC] [Servlet.LOG]: SimpleFileServlet: init
[27.01.04 18:08:03:510 MSK] 3c7c7ab0 ApplicationMg A WSVR0221I: Application
started: IBMUTC
[27.01.04 18:08:03:604 MSK] 3c7c7ab0 HttpTransport A SRVE0171I: Transport
http is listening on port 7 080.
[27.01.04 18:08:03:650 MSK] 3c7c7ab0 HttpTransport A SRVE0171I: Transport
https is listening on port 9 443.
[27.01.04 18:08:03:807 MSK] 3c7c7ab0 RMIConnectorC A ADMC0026I: RMI
Connector available at port 2809
[27.01.04 18:08:03:838 MSK] 3c7c7ab0 WsServer A WSVR0001I: Server server1
open for e-business
[27.01.04 18:08:07:369 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:08:07:416 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:08:17:446 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:08:17:509 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:08:27:414 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:08:27:445 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:08:37:366 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:08:37:413 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:08:47:365 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:08:47:396 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:08:57:364 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:08:57:411 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:09:03:067 MSK] 5b36bab2 JMSExceptionL E WMSG0018E: Error on
JMSConnection for MDB ExchangeListener , JMSDestination jms/InTransport_Q :
javax.jms.JMSException: MQJMS1025: failed to browse message
at
com.ibm.mq.jms.services.ConfigEnvironment.newException(ConfigEnvironment.jav
a:540)
at
com.ibm.mq.jms.MQQueueAgentThread1Impl.browse(MQQueueAgentThread1Impl.java:3
24)
at com.ibm.mq.jms.MQQueueAgentThread.run(MQQueueAgentThread.java:1553)
at java.lang.Thread.run(Thread.java:568)
---- Begin backtrace for Nested Throwables
com.ibm.mq.MQException: MQJE001: Completion Code 2, Reason 2026
at
com.ibm.mq.jms.MQQueueAgentThread1Impl.makeSuitableForDelivery(MQQueueAgentT
hread1Impl.java:583)
at
com.ibm.mq.jms.MQQueueAgentThread1Impl.browse(MQQueueAgentThread1Impl.java:2
51)
at com.ibm.mq.jms.MQQueueAgentThread.run(MQQueueAgentThread.java:1553)
at java.lang.Thread.run(Thread.java:568)
[27.01.04 18:09:03:098 MSK] 5b36bab2 JMSExceptionL E WMSG0057E: Error on
JMSConnection for MDB ExchangeListener , JMSDestination jms/InTransport_Q ,
JMS Linked Exception : com.ibm.mq.MQException: MQJE001: Completion Code 2,
Reason 2026
at
com.ibm.mq.jms.MQQueueAgentThread1Impl.makeSuitableForDelivery(MQQueueAgentT
hread1Impl.java:583)
at
com.ibm.mq.jms.MQQueueAgentThread1Impl.browse(MQQueueAgentThread1Impl.java:2
51)
at com.ibm.mq.jms.MQQueueAgentThread.run(MQQueueAgentThread.java:1553)
at java.lang.Thread.run(Thread.java:568)
[27.01.04 18:09:03:145 MSK] 5b36bab2 ConnectionEve A J2CA0056I: The
Connection Manager received a fatal connection error from the Resource
Adaptor for resource jms/Transport_QCF. The exception which was received is
javax.jms.JMSException: MQJMS1025: failed to browse message
[27.01.04 18:09:07:379 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:09:07:410 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:09:17:410 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:09:17:441 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:09:27:378 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:09:27:425 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:09:28:847 MSK] 5c9a3ab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: /layout/classicLayout.jsp: init
[27.01.04 18:09:28:956 MSK] 5c9a3ab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: /common/header.jsp: init
[27.01.04 18:09:28:972 MSK] 5c9a3ab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: /common/menu.jsp: init
[27.01.04 18:09:29:034 MSK] 5c9a3ab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: /common/messages.jsp: init
[27.01.04 18:09:29:050 MSK] 5c9a3ab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: /presearchmessages.jsp: init
[27.01.04 18:09:29:237 MSK] 5c9a3ab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: /common/empty.jsp: init
[27.01.04 18:09:29:253 MSK] 5c9a3ab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: /common/footer.jsp: init
[27.01.04 18:09:32:128 MSK] 5c9a3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:09:32:222 MSK] 5c9a3ab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: /searchmessages.jsp: init
[27.01.04 18:09:37:362 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:09:37:409 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:09:47:378 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:09:47:409 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:09:57:362 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:09:57:393 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:10:07:361 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:10:07:393 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:10:17:361 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:10:17:408 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:10:27:376 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:10:27:392 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:10:37:360 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:10:37:392 MSK] 2c5b3ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:10:44:329 MSK] c747ab2 ApplicationMg A WSVR0217I: Stopping
application: UTS
[27.01.04 18:10:44:610 MSK] c747ab2 WebContainer A SRVE0170I: Stopping Web
Module: Transport Web Administration.
[27.01.04 18:10:44:626 MSK] c747ab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: InvokerServlet: destroy
[27.01.04 18:10:44:641 MSK] c747ab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: SetupServlet: destroy
[27.01.04 18:10:44:657 MSK] c747ab2 SystemOut O 18:10:44,657 INFO
TransportMgrBean.stopModemTimer - Unregistering ModemConnectionListener
18:10:44,657 INFO TransportMgrBean.stopOutboxDirectoryTimer - Unregistering
DirectoryWatcher
[27.01.04 18:10:44:673 MSK] c747ab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: SimpleFileServlet: destroy
[27.01.04 18:10:44:673 MSK] c747ab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: JSP 1.2 Processor: destroy
[27.01.04 18:10:44:688 MSK] c747ab2 EJBContainerI I WSVR0041I: Stopping EJB
jar: TransportEJB.jar
[27.01.04 18:10:44:735 MSK] c747ab2 EJBContainerI I WSVR0041I: Stopping EJB
jar: SequenceEJB.jar
[27.01.04 18:10:44:751 MSK] c747ab2 EJBContainerI I WSVR0041I: Stopping EJB
jar: JournalEJB.jar
[27.01.04 18:10:44:751 MSK] c747ab2 EJBContainerI I WSVR0041I: Stopping EJB
jar: ExchangeEJB.jar
[27.01.04 18:10:44:766 MSK] c747ab2 EJBContainerI I WSVR0041I: Stopping EJB
jar: ConfigEJB.jar
[27.01.04 18:10:44:782 MSK] c747ab2 ApplicationMg A WSVR0220I: Application
stopped: UTS
[27.01.04 18:10:50:422 MSK] 5db8fab2 ApplicationMg A WSVR0200I: Starting
application: UTS
[27.01.04 18:10:57:516 MSK] 5db8fab2 EJBContainerI I WSVR0207I: Preparing to
start EJB jar: ConfigEJB.jar
[27.01.04 18:10:57:610 MSK] 5db8fab2 EJBContainerI I WSVR0037I: Starting EJB
jar: ConfigEJB.jar
[27.01.04 18:10:57:672 MSK] 5db8fab2 EJBContainerI I WSVR0207I: Preparing to
start EJB jar: ExchangeEJB.jar
[27.01.04 18:10:57:735 MSK] 5db8fab2 EJBContainerI I WSVR0037I: Starting EJB
jar: ExchangeEJB.jar
[27.01.04 18:10:57:797 MSK] 5db8fab2 EJBContainerI I WSVR0207I: Preparing to
start EJB jar: JournalEJB.jar
[27.01.04 18:10:58:047 MSK] 5db8fab2 EJBContainerI I WSVR0037I: Starting EJB
jar: JournalEJB.jar
[27.01.04 18:10:58:172 MSK] 5db8fab2 EJBContainerI I WSVR0207I: Preparing to
start EJB jar: SequenceEJB.jar
[27.01.04 18:10:58:235 MSK] 5db8fab2 EJBContainerI I WSVR0037I: Starting EJB
jar: SequenceEJB.jar
[27.01.04 18:10:58:282 MSK] 5db8fab2 EJBContainerI I WSVR0207I: Preparing to
start EJB jar: TransportEJB.jar
[27.01.04 18:10:58:781 MSK] 5db8fab2 EJBContainerI I WSVR0037I: Starting EJB
jar: TransportEJB.jar
[27.01.04 18:10:59:156 MSK] 5db8fab2 WebContainer A SRVE0169I: Loading Web
Module: Transport Web Administration.
[27.01.04 18:10:59:547 MSK] 5db8fab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: JSP 1.2 Processor: init
[27.01.04 18:11:00:063 MSK] 5db8fab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: FormLoginServlet: init
[27.01.04 18:11:00:063 MSK] 5db8fab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: FormLogoutServlet: init
[27.01.04 18:11:00:063 MSK] 5db8fab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: SimpleFileServlet: init
[27.01.04 18:11:00:063 MSK] 5db8fab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: InvokerServlet: init
[27.01.04 18:11:00:078 MSK] 5db8fab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: SetupServlet: init
18:11:00,156 INFO TransportMgrBean.startModemTimer - Registering
ModemConnectionListener, check interval: 10000 ms
Timer service [WebSphere:name=timer1] with listener
[ru.lanit.uts.transport.util.ModemConnectionListener@46877abb], interval =
10000 has been created
[27.01.04 18:11:00:469 MSK] 5db8fab2 SystemOut O 18:11:00,469 INFO
TransportMgrBean.startOutboxDirectoryTimer - Registering DirectoryWhatcher,
check interval: 10000 ms
Timer service [WebSphere:name=timer2] with listener
[ru.lanit.uts.transport.util.DirectoryWhatcher@18017abc], interval = 10000
has been created
[27.01.04 18:11:00:578 MSK] 17ed7ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:11:00:578 MSK] 5db8fab2 WebGroup I SRVE0180I: [Transport Web
Administration] [/transport] [Servlet.LOG]: action: init
[27.01.04 18:11:00:703 MSK] 17ed7ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:11:02:110 MSK] 5db8fab2 ApplicationMg A WSVR0221I: Application
started: UTS
[27.01.04 18:11:02:359 MSK] 3546fab2 SystemOut O Receiving message with ID
JMS Message class: jms_text
JMSType: null
JMSDeliveryMode: 2
JMSExpiration: 0
JMSPriority: 4
JMSMessageID: ID:414d51205741535f6c6f63616c686f73807c164020000601
JMSTimestamp: 1075215561395
JMSCorrelationID:null
JMSDestination: queue:///WQ_InTransport_Q
JMSReplyTo: null
JMSRedelivered: false
JMS_IBM_PutDate:20040127
JMSXAppID:Websphere MQ Client for Java
JMS_IBM_Format:MQSTR
JMS_IBM_PutApplType:28
JMS_IBM_MsgType:8
JMSXUserID:wasadmin
JMS_IBM_PutTime:14592139
JMSXDeliveryCount:1
AAAABBB
[27.01.04 18:11:02:359 MSK] 3546fab2 SystemOut O 18:11:02,359 INFO
ExchangeListenerBean.onMessage - Receiving message with ID
ID:414d51205741535f6c6f63616c686f73807c164020000601 ...
18:11:02,375 ERROR ExchangeListenerBean.onMessage -
java.lang.NumberFormatException: null
java.lang.NumberFormatException: null
at java.lang.Integer.parseInt(Integer.java(Compiled Code))
at java.lang.Integer.parseInt(Integer.java:498)
at
ru.lanit.uts.transport.util.MessageAssembler.isAllChanksInQueue(MessageAssem
bler.java:283)
at
ru.lanit.uts.transport.util.MessageAssembler.assembleMessage(MessageAssemble
r.java:142)
at
ru.lanit.uts.transport.util.MessageAssembler.retrieveAssembledMessageFromInb
ox(MessageAssembler.java:118)
at
ru.lanit.uts.exchange.ExchangeListenerBean.onMessage(ExchangeListenerBean.ja
va:62)
at
com.ibm.ejs.jms.listener.MDBWrapper$PriviledgedOnMessage.run(MDBWrapper.java
:208)
at
com.ibm.ws.security.util.AccessController.doPrivileged(AccessController.java
(Compiled Code))
at com.ibm.ejs.jms.listener.MDBWrapper.callOnMessage(MDBWrapper.java:197)
at com.ibm.ejs.jms.listener.MDBWrapper.onMessage(MDBWrapper.java:175)
at com.ibm.mq.jms.MQSession.run(MQSession.java:1043)
at com.ibm.ejs.jms.JMSSessionHandle.run(JMSSessionHandle.java:923)
at
com.ibm.ejs.jms.listener.ServerSession.connectionConsumerOnMessage(ServerSes
sion.java:697)
at com.ibm.ejs.jms.listener.ServerSession.onMessage(ServerSession.java:482)
at com.ibm.ejs.jms.listener.ServerSession.dispatch(ServerSession.java:449)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:79
)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl
.java(Compiled Code))
at java.lang.reflect.Method.invoke(Method.java(Compiled Code))
at
com.ibm.ejs.jms.listener.ServerSessionDispatcher.dispatch(ServerSessionDispa
tcher.java:37)
at com.ibm.ejs.container.MDBWrapper.onMessage(MDBWrapper.java:91)
at com.ibm.ejs.container.MDBWrapper.onMessage(MDBWrapper.java:127)
at com.ibm.ejs.jms.listener.ServerSession.run(ServerSession.java:372)
at com.ibm.ws.util.ThreadPool$Worker.run(ThreadPool.java:593)
>>>>>>>>>>> GETTING JMS CONNECTION <<<<<<<<<<
[27.01.04 18:11:10:422 MSK] 17ed7ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:11:20:437 MSK] 17ed7ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
[27.01.04 18:11:20:625 MSK] 17ed7ab2 SystemOut O >>>>>>>>>>> GETTING JMS
CONNECTION <<<<<<<<<<
Ron Bower
mqrc 2026
2026 0x000007ea MQRC_MD_ERROR
I'd use the amqsbcg program to browse your queue to look at your message.
My guess would be that you manually built a JMS type of message and not all
of the header fields are correct.
Ron
PS: If you don't have amqsbcg on your system, there is a java version
available at: http://www.developer.ibm.com/tech/sampmq.html
"Savotchkin Egor" <es...@lanit.ru> wrote in message
news:bv5vn1$sk4$1...@news.boulder.ibm.com...
Savotchkin Egor
The problem disappeared when I changed the MDB message selector from :
"FRESH='true' AND TEST_MSG<>'true'" to "FRESH='true'".
After restarting the app the MDB seemed to not use message selector that was
why the app worked after restarting.
It is strange anyway. The message selector syntax with "TEST_MSG<>'true'"
seems to be correct.
Egor.
"Ron Bower" <bo...@n0sp8m.us.ibm.com> wrote in message
news:bv6146$kbm$1...@news.boulder.ibm.com...