onException() called on JBoss shutdown, but not UILServerILS
Hi!
I have a JMS client connecting and setting an ExceptionListener.
ExceptionListener.onException() is called fine when JBoss is shutdown.
I get a nice little exception like this:
org.jboss.mq.SpyJMSException: Exiting on IOE; - nested throwable: (java.net.SocketException: Connection reset)
at org.jboss.mq.Connection.asynchFailure(Connection.java:429)
at org.jboss.mq.il.uil2.UILClientILService.asynchFailure(UILClientILService.java:145)
at org.jboss.mq.il.uil2.SocketManager$ReadTask.handleStop(SocketManager.java:398)
at org.jboss.mq.il.uil2.SocketManager$ReadTask.run(SocketManager.java:332)
at java.lang.Thread.run(Unknown Source)
Caused by: java.net.SocketException: Connection reset
at java.net.SocketInputStream.read(Unknown Source)
at java.io.BufferedInputStream.fill(Unknown Source)
at java.io.BufferedInputStream.read(Unknown Source)
at org.jboss.util.stream.NotifyingBufferedInputStream.read(NotifyingBufferedInputStream.java:67)
at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source)
at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source)
at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source)
at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source)
at java.io.ObjectInputStream$BlockDataInputStream.readByte(Unknown Source)
at java.io.ObjectInputStream.readByte(Unknown Source)
at org.jboss.mq.il.uil2.SocketManager$ReadTask.run(SocketManager.java:279)
... 1 more
However, when I call just UILServerILServiceMBean's stop(), the UIL socket (8093) is closed, but the ExceptionListener.onException() is never invoked from the org.jboss.mq.il.uil2.SocketManager.ReadTask. After UILServerILServiceMBean.stop() is called, I cannot telnet to the 8093 port, so I know there is nothing listening on it. What can I do to make the SocketManager.ReadTask recognize 8093 is no longer open?
The implication of this, obviously, is that I have no ability to detect JMS errors and reconnect to another JMS server.
JBoss Server Log:
The log shows when the stopping and (I also tried destroy()) destroying the services. However, the client log shows that the SocketManager.ReadTask does not error and call onException(). Why does the server keep accepting ping requests after the services are destroyed???
2005-01-14 13:17:12,955 DEBUG [org.jboss.mq.server.jmx.InterceptorLoader] Starting jboss.mq:service=TracingInterceptor
2005-01-14 13:17:12,971 DEBUG [org.jboss.mq.server.jmx.InterceptorLoader] Started jboss.mq:service=TracingInterceptor
2005-01-14 13:17:12,971 DEBUG [org.jboss.mq.server.jmx.Invoker] Starting jboss.mq:service=Invoker
2005-01-14 13:17:12,971 DEBUG [org.jboss.mq.server.jmx.Invoker] Started jboss.mq:service=Invoker
2005-01-14 13:17:12,971 DEBUG [org.jboss.mq.il.http.HTTPServerILService] Starting jboss.mq:service=InvocationLayer,type=HTTP
2005-01-14 13:17:12,986 TRACE [org.jboss.mq.il.http.HTTPClient] created(String http://10.82.100.103:8080/jbossmq-httpil/HTTPServerILServlet)
2005-01-14 13:17:13,065 DEBUG [org.jboss.mq.il.http.HTTPServerILService] Started jboss.mq:service=InvocationLayer,type=HTTP
2005-01-14 13:17:13,065 DEBUG [org.jboss.mq.il.jvm.JVMServerILService] Starting jboss.mq:service=InvocationLayer,type=JVM
2005-01-14 13:17:13,065 DEBUG [org.jboss.mq.il.jvm.JVMServerILService] Started jboss.mq:service=InvocationLayer,type=JVM
2005-01-14 13:17:13,065 DEBUG [org.jboss.mq.il.oil.OILServerILService] Starting jboss.mq:service=InvocationLayer,type=OIL
2005-01-14 13:17:13,065 INFO [org.jboss.mq.il.oil.OILServerILService] JBossMQ OIL service available at : /0.0.0.0:8090
2005-01-14 13:17:13,065 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : getThreadGroup
2005-01-14 13:17:13,065 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : getThreadGroup
2005-01-14 13:17:13,096 DEBUG [org.jboss.mq.il.oil.OILServerILService] Started jboss.mq:service=InvocationLayer,type=OIL
2005-01-14 13:17:13,096 DEBUG [org.jboss.mq.il.oil2.OIL2ServerILService] Starting jboss.mq:service=InvocationLayer,type=OIL2
2005-01-14 13:17:13,096 INFO [org.jboss.mq.il.oil2.OIL2ServerILService] JBossMQ OIL2 service available at : /0.0.0.0:8092
2005-01-14 13:17:13,096 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : getThreadGroup
2005-01-14 13:17:13,096 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : getThreadGroup
2005-01-14 13:17:13,111 DEBUG [org.jboss.mq.il.oil2.OIL2ServerILService] Started jboss.mq:service=InvocationLayer,type=OIL2
2005-01-14 13:17:13,111 DEBUG [org.jboss.mq.il.rmi.RMIServerILService] Starting jboss.mq:service=InvocationLayer,type=RMI
2005-01-14 13:17:13,190 DEBUG [org.jboss.mq.il.rmi.RMIServerILService] Started jboss.mq:service=InvocationLayer,type=RMI
2005-01-14 13:17:13,190 DEBUG [org.jboss.mq.il.uil2.UILServerILService] Starting jboss.mq:service=InvocationLayer,type=UIL2
2005-01-14 13:17:13,190 INFO [org.jboss.mq.il.uil2.UILServerILService] JBossMQ UIL service available at : /0.0.0.0:8093
2005-01-14 13:17:13,190 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : getThreadGroup
2005-01-14 13:17:13,190 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : getThreadGroup
2005-01-14 13:17:13,283 DEBUG [org.jboss.mq.il.uil2.UILServerILService] Started jboss.mq:service=InvocationLayer,type=UIL2
2005-01-14 13:17:13,283 DEBUG [org.jboss.mq.server.jmx.Queue.DLQ] Starting jboss.mq.destination:service=Queue,name=DLQ
2005-01-14 13:17:13,283 DEBUG [org.jboss.mq.pm.jdbc3.PersistenceManager] Restored 0 message(s) to: QUEUE.DLQ
2005-01-14 13:17:13,283 INFO [org.jboss.mq.server.jmx.Queue.DLQ] Bound to JNDI name: queue/DLQ
2005-01-14 13:17:13,283 TRACE [org.jboss.mq.security.SecurityMetadata] Adding role: Role {name=Guest Global;read=true;write=true;create=false}
2005-01-14 13:17:13,283 DEBUG [org.jboss.mq.server.jmx.Queue.DLQ] Started jboss.mq.destination:service=Queue,name=DLQ
2005-01-14 13:17:13,315 INFO [org.jboss.resource.adapter.jms.JmsManagedConnectionFactory.JmsXA] Bound connection factory for resource adapter for ConnectionManager 'jboss.jca:service=TxCM,name=JmsXA to JNDI name 'java:/JmsXA'
2005-01-14 13:17:14,205 INFO [org.jboss.web.tomcat.tc5.TomcatDeployer] deploy, ctxPath=/jmx-console, warUrl=file:/C:/jboss-3.2.4RC2/server/dev-clustered/deploy/jmx-console.war/
2005-01-14 13:17:14,268 DEBUG [tomcat.localhost./jmx-console.Context] Starting tomcat.localhost./jmx-console.Context
2005-01-14 13:17:14,268 DEBUG [tomcat.localhost./jmx-console.Context] Configuring default Resources
2005-01-14 13:17:14,268 DEBUG [tomcat.localhost./jmx-console.Context] Processing standard container startup
2005-01-14 13:17:14,283 DEBUG [tomcat.localhost./jmx-console.Context] Setting deployment descriptor public ID to '-//Sun Microsystems, Inc.//DTD Web Application 2.3//EN'
2005-01-14 13:17:14,315 DEBUG [tomcat.localhost./jmx-console.Context] Setting deployment descriptor public ID to '-//Sun Microsystems, Inc.//DTD Web Application 2.3//EN'
2005-01-14 13:17:14,346 DEBUG [tomcat.localhost./jmx-console.Context] Posting standard context attributes
2005-01-14 13:17:14,346 DEBUG [tomcat.localhost./jmx-console.Context] Configuring application event listeners
2005-01-14 13:17:14,346 DEBUG [tomcat.localhost./jmx-console.Context] Sending application start events
2005-01-14 13:17:14,346 DEBUG [tomcat.localhost./jmx-console.Context] Starting filters
2005-01-14 13:17:14,361 DEBUG [tomcat.localhost./jmx-console.Context] Starting completed
2005-01-14 13:17:14,361 DEBUG [tomcat.localhost./jmx-console.Context] Checking for jboss.web:j2eeType=WebModule,name=//localhost/jmx-console,J2EEApplication=none,J2EEServer=none
2005-01-14 13:17:15,033 INFO [org.jboss.web.tomcat.tc5.TomcatDeployer] deploy, ctxPath=/web-console, warUrl=file:/C:/jboss-3.2.4RC2/server/dev-clustered/tmp/deploy/tmp62089web-console.war/
2005-01-14 13:17:15,065 DEBUG [tomcat.localhost./web-console.Context] Starting tomcat.localhost./web-console.Context
2005-01-14 13:17:15,065 DEBUG [tomcat.localhost./web-console.Context] Configuring default Resources
2005-01-14 13:17:15,065 DEBUG [tomcat.localhost./web-console.Context] Processing standard container startup
2005-01-14 13:17:15,096 DEBUG [tomcat.localhost./web-console.Context] Setting deployment descriptor public ID to '-//Sun Microsystems, Inc.//DTD Web Application 2.3//EN'
2005-01-14 13:17:15,158 DEBUG [tomcat.localhost./web-console.Context] Setting deployment descriptor public ID to '-//Sun Microsystems, Inc.//DTD Web Application 2.3//EN'
2005-01-14 13:17:15,174 DEBUG [tomcat.localhost./web-console.Context] Posting standard context attributes
2005-01-14 13:17:15,174 DEBUG [tomcat.localhost./web-console.Context] Configuring application event listeners
2005-01-14 13:17:15,174 DEBUG [tomcat.localhost./web-console.Context] Sending application start events
2005-01-14 13:17:15,174 DEBUG [tomcat.localhost./web-console.Context] Starting filters
2005-01-14 13:17:15,627 DEBUG [tomcat.localhost./web-console.Context] Starting completed
2005-01-14 13:17:15,627 DEBUG [tomcat.localhost./web-console.Context] Checking for jboss.web:j2eeType=WebModule,name=//localhost/web-console,J2EEApplication=none,J2EEServer=none
2005-01-14 13:17:16,221 INFO [org.jboss.system.server.Server] JBoss (MX MicroKernel) [3.2.4RC2 (build: CVSTag=Branch_3_2 date=200405170233)] Started in 29s:546ms
2005-01-14 13:17:22,314 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : getThreadGroup
2005-01-14 13:17:22,314 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : getThreadGroup
2005-01-14 13:17:22,314 TRACE [org.jboss.mq.il.uil2.SocketManager] start called
java.lang.Exception: Start stack trace
at org.jboss.mq.il.uil2.SocketManager.start(SocketManager.java:94)
at org.jboss.mq.il.uil2.UILServerILService.run(UILServerILService.java:127)
at java.lang.Thread.run(Thread.java:534)
2005-01-14 13:17:22,377 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin ReadTask.run
2005-01-14 13:17:22,377 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectInputStream
2005-01-14 13:17:22,377 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_authenticate, msgID: 1
2005-01-14 13:17:22,377 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.CheckUserMsg26935588[msgType: m_authenticate, msgID: 1, error: null]
2005-01-14 13:17:22,377 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin WriteTask.run
2005-01-14 13:17:22,377 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectOutputStream
2005-01-14 13:17:22,377 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 22
2005-01-14 13:17:22,377 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : authenticate
2005-01-14 13:17:22,377 TRACE [org.jboss.mq.security.ServerSecurityInterceptor] Autenticating user SYSTEM/password
2005-01-14 13:17:22,392 DEBUG [cdot.jboss.JDBCLoginModule] initialize: Enter
2005-01-14 13:17:22,392 DEBUG [cdot.jboss.JDBCLoginModule] dsJndiName=java:/OracleDS
2005-01-14 13:17:22,392 DEBUG [cdot.jboss.JDBCLoginModule] principalsQuery=SELECT PASSWORD_TXT FROM SECURITY_USER WHERE TYPE_SECURITY_STATUS_CD = 1 AND USER_NAME_TXT=?
2005-01-14 13:17:22,392 DEBUG [cdot.jboss.JDBCLoginModule] rolesQuery=SELECT GROUP_NAME_TXT, ROLE_NAME_TXT FROM V_ROLES WHERE USER_NAME_TXT = ?
2005-01-14 13:17:22,392 DEBUG [cdot.jboss.JDBCLoginModule] initialize: Exit
2005-01-14 13:17:22,439 DEBUG [cdot.jboss.JDBCLoginModule] USER_NAME_TXT = SYSTEM, ROLE_NAME_TXT = ITS Administrator
2005-01-14 13:17:22,439 DEBUG [cdot.jboss.JDBCLoginModule] USER_NAME_TXT = SYSTEM, ROLE_NAME_TXT = Guest Global
2005-01-14 13:17:22,439 DEBUG [cdot.jboss.JDBCLoginModule] USER_NAME_TXT = SYSTEM, ROLE_NAME_TXT = Admin Global
2005-01-14 13:17:22,439 TRACE [org.jboss.mq.security.SecurityManager] Username: SYSTEM is authenticated
2005-01-14 13:17:22,455 TRACE [org.jboss.mq.security.SecurityManager] Adding group : class org.jboss.security.NestableGroup Roles(members:Admin Global,SYSTEM,ITS Administrator,Guest Global)
2005-01-14 13:17:22,455 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : authenticate
2005-01-14 13:17:22,455 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.CheckUserMsg26935588[msgType: m_authenticate, msgID: 1, error: null]
2005-01-14 13:17:22,455 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.CheckUserMsg26935588[msgType: m_authenticate, msgID: 1, error: null]
2005-01-14 13:17:22,455 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 22
2005-01-14 13:17:22,455 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.CheckUserMsg26935588[msgType: m_authenticate, msgID: 1, error: null]
2005-01-14 13:17:22,455 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_checkUser, msgID: 2
2005-01-14 13:17:22,455 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.CheckUserMsg2523263[msgType: m_checkUser, msgID: 2, error: null]
2005-01-14 13:17:22,455 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 20
2005-01-14 13:17:22,455 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : checkUser
2005-01-14 13:17:22,455 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : SYSTEM
2005-01-14 13:17:22,455 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : (password not shown)
2005-01-14 13:17:22,455 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : checkUser
2005-01-14 13:17:22,455 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.CheckUserMsg2523263[msgType: m_checkUser, msgID: 2, error: null]
2005-01-14 13:17:22,455 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.CheckUserMsg2523263[msgType: m_checkUser, msgID: 2, error: null]
2005-01-14 13:17:22,455 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 20
2005-01-14 13:17:22,455 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.CheckUserMsg2523263[msgType: m_checkUser, msgID: 2, error: null]
2005-01-14 13:17:22,471 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_setSpyDistributedConnection, msgID: 3
2005-01-14 13:17:22,486 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.ConnectionTokenMsg6740524[msgType: m_setSpyDistributedConnection, msgID: 3, error: null]
2005-01-14 13:17:22,486 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 15
2005-01-14 13:17:22,486 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Setting up the UILClientIL Connection
2005-01-14 13:17:22,486 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.ConnectionTokenMsg6740524[msgType: m_setSpyDistributedConnection, msgID: 3, error: null]
2005-01-14 13:17:22,486 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.ConnectionTokenMsg6740524[msgType: m_setSpyDistributedConnection, msgID: 3, error: null]
2005-01-14 13:17:22,486 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] The UILClientIL Connection is set up
2005-01-14 13:17:22,486 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 15
2005-01-14 13:17:22,486 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.ConnectionTokenMsg6740524[msgType: m_setSpyDistributedConnection, msgID: 3, error: null]
2005-01-14 13:17:22,502 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_getID, msgID: 4
2005-01-14 13:17:22,502 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.GetIDMsg8238932[msgType: m_getID, msgID: 4, error: null]
2005-01-14 13:17:22,502 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 9
2005-01-14 13:17:22,502 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : getID
2005-01-14 13:17:22,502 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : getID
2005-01-14 13:17:22,502 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.GetIDMsg8238932[msgType: m_getID, msgID: 4, error: null]
2005-01-14 13:17:22,502 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.GetIDMsg8238932[msgType: m_getID, msgID: 4, error: null]
2005-01-14 13:17:22,502 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 9
2005-01-14 13:17:22,502 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.GetIDMsg8238932[msgType: m_getID, msgID: 4, error: null]
2005-01-14 13:17:22,517 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_ping, msgID: 5
2005-01-14 13:17:22,517 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.PingMsg22565791[msgType: m_ping, msgID: 5, error: null]
2005-01-14 13:17:22,517 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 21
2005-01-14 13:17:22,533 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : ping
2005-01-14 13:17:22,533 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : 1105733842502
2005-01-14 13:17:22,533 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.PingMsg31070867[msgType: m_pong, msgID: -2147483647, error: null]
2005-01-14 13:17:22,533 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.PingMsg31070867[msgType: m_pong, msgID: -2147483647, error: null]
2005-01-14 13:17:22,533 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : ping
2005-01-14 13:17:22,533 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 21
2005-01-14 13:17:22,533 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.PingMsg31070867[msgType: m_pong, msgID: -2147483647, error: null]
2005-01-14 13:17:22,564 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_subscribe, msgID: 6
2005-01-14 13:17:22,877 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.SubscribeMsg29313387[msgType: m_subscribe, msgID: 6, error: null]
2005-01-14 13:17:22,877 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 16
2005-01-14 13:17:22,877 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : subscribe
2005-01-14 13:17:22,877 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : Subscription[subId=-2147483648 destination=TOPIC.AlarmTopic messageSelector=null NoLocal Create]
2005-01-14 13:17:22,877 TRACE [org.jboss.mq.security.ServerSecurityInterceptor] Checking subscribe authorize on ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 sub=Subscription[subId=-2147483648 destination=TOPIC.AlarmTopic messageSelector=null NoLocal Create]
2005-01-14 13:17:22,877 TRACE [org.jboss.mq.security.SecurityManager] Checking authorize on subjectInfo: SubjectInfo {subject=Subject:
Principal: SYSTEM
Principal: Roles(members:Admin Global,SYSTEM,ITS Administrator,Guest Global)
;principal=SYSTEM;roles=Roles(members:Admin Global,SYSTEM,ITS Administrator,Guest Global) for rolePrincipals [Guest Global]
2005-01-14 13:17:22,892 TRACE [org.jboss.mq.server.ClientConsumer] Adding subscription for: Subscription[subId=-2147483648 destination=TOPIC.AlarmTopic messageSelector=null NoLocal Create]
2005-01-14 13:17:22,892 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : subscribe
2005-01-14 13:17:22,892 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.SubscribeMsg29313387[msgType: m_subscribe, msgID: 6, error: null]
2005-01-14 13:17:22,892 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.SubscribeMsg29313387[msgType: m_subscribe, msgID: 6, error: null]
2005-01-14 13:17:22,892 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.SubscribeMsg29313387[msgType: m_subscribe, msgID: 6, error: null]
2005-01-14 13:17:22,892 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 16
2005-01-14 13:17:22,892 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_setEnabled, msgID: 7
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.EnableConnectionMsg16166428[msgType: m_setEnabled, msgID: 7, error: null]
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 14
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : setEnabled
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : true
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.server.ClientConsumer] ClientConsumer:ID:1->setEnabled(enabled=true)
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : setEnabled
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.EnableConnectionMsg16166428[msgType: m_setEnabled, msgID: 7, error: null]
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.EnableConnectionMsg16166428[msgType: m_setEnabled, msgID: 7, error: null]
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 14
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.EnableConnectionMsg16166428[msgType: m_setEnabled, msgID: 7, error: null]
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_receive, msgID: 8
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.ReceiveMsg18929335[msgType: m_receive, msgID: 8, error: null]
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 13
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : receive
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : -2147483648
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : 0
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.security.ServerSecurityInterceptor] Checking receive authorize on ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 subId=-2147483648
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.security.SecurityManager] Checking authorize on subjectInfo: SubjectInfo {subject=Subject:
Principal: SYSTEM
Principal: Roles(members:Admin Global,SYSTEM,ITS Administrator,Guest Global)
;principal=SYSTEM;roles=Roles(members:Admin Global,SYSTEM,ITS Administrator,Guest Global) for rolePrincipals [Guest Global]
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.server.BasicQueue] receive Subscription[subId=-2147483648connection=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 destination=TOPIC.AlarmTopic messageSelector=null NoLocal Create] wait=true org.jboss.mq.server.ExclusiveQueue@f08d0f{id=TOPIC.AlarmTopic.ID:1.-2147483648}
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.server.BasicQueue] addReceiver Subscription[subId=-2147483648connection=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 destination=TOPIC.AlarmTopic messageSelector=null NoLocal Create] org.jboss.mq.server.ExclusiveQueue@f08d0f{id=TOPIC.AlarmTopic.ID:1.-2147483648}
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : receive
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.ReceiveMsg18929335[msgType: m_receive, msgID: 8, error: null]
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.ReceiveMsg18929335[msgType: m_receive, msgID: 8, error: null]
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 13
2005-01-14 13:17:22,908 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.ReceiveMsg18929335[msgType: m_receive, msgID: 8, error: null]
2005-01-14 13:18:22,515 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_ping, msgID: 9
2005-01-14 13:18:22,515 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.PingMsg23141100[msgType: m_ping, msgID: 9, error: null]
2005-01-14 13:18:22,515 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 21
2005-01-14 13:18:22,515 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : ping
2005-01-14 13:18:22,515 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : 1105733902515
2005-01-14 13:18:22,515 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.PingMsg3054710[msgType: m_pong, msgID: -2147483646, error: null]
2005-01-14 13:18:22,515 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.PingMsg3054710[msgType: m_pong, msgID: -2147483646, error: null]
2005-01-14 13:18:22,515 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : ping
2005-01-14 13:18:22,515 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 21
2005-01-14 13:18:22,515 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.PingMsg3054710[msgType: m_pong, msgID: -2147483646, error: null]
2005-01-14 13:18:35,687 DEBUG [org.jboss.mq.il.uil2.UILServerILService] Stopping jboss.mq:service=InvocationLayer,type=UIL2
2005-01-14 13:18:35,687 DEBUG [org.jboss.mq.il.uil2.UILServerILService] Stopped jboss.mq:service=InvocationLayer,type=UIL2
2005-01-14 13:19:22,529 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_ping, msgID: 10
2005-01-14 13:19:22,529 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.PingMsg7473380[msgType: m_ping, msgID: 10, error: null]
2005-01-14 13:19:22,529 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 21
2005-01-14 13:19:22,529 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : ping
2005-01-14 13:19:22,529 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : 1105733962529
2005-01-14 13:19:22,529 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.PingMsg26130400[msgType: m_pong, msgID: -2147483645, error: null]
2005-01-14 13:19:22,529 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.PingMsg26130400[msgType: m_pong, msgID: -2147483645, error: null]
2005-01-14 13:19:22,529 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.PingMsg26130400[msgType: m_pong, msgID: -2147483645, error: null]
2005-01-14 13:19:22,529 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : ping
2005-01-14 13:19:22,529 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 21
2005-01-14 13:20:22,543 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_ping, msgID: 11
2005-01-14 13:20:22,543 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.PingMsg7871956[msgType: m_ping, msgID: 11, error: null]
2005-01-14 13:20:22,543 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 21
2005-01-14 13:20:22,543 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : ping
2005-01-14 13:20:22,543 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : 1105734022543
2005-01-14 13:20:22,543 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.PingMsg29221778[msgType: m_pong, msgID: -2147483644, error: null]
2005-01-14 13:20:22,543 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.PingMsg29221778[msgType: m_pong, msgID: -2147483644, error: null]
2005-01-14 13:20:22,543 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.PingMsg29221778[msgType: m_pong, msgID: -2147483644, error: null]
2005-01-14 13:20:22,543 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : ping
2005-01-14 13:20:22,543 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 21
2005-01-14 13:21:08,026 DEBUG [org.jboss.mq.il.http.HTTPServerILService] Stopping jboss.mq:service=InvocationLayer,type=HTTP
2005-01-14 13:21:08,026 DEBUG [org.jboss.mq.il.http.HTTPServerILService] Stopped jboss.mq:service=InvocationLayer,type=HTTP
2005-01-14 13:21:08,026 DEBUG [org.jboss.mq.il.jvm.JVMServerILService] Stopping jboss.mq:service=InvocationLayer,type=JVM
2005-01-14 13:21:08,026 DEBUG [org.jboss.mq.il.jvm.JVMServerILService] Stopped jboss.mq:service=InvocationLayer,type=JVM
2005-01-14 13:21:08,026 DEBUG [org.jboss.mq.il.oil.OILServerILService] Stopping jboss.mq:service=InvocationLayer,type=OIL
2005-01-14 13:21:08,026 DEBUG [org.jboss.mq.il.oil.OILServerILService] Stopped jboss.mq:service=InvocationLayer,type=OIL
2005-01-14 13:21:08,026 DEBUG [org.jboss.mq.il.oil2.OIL2ServerILService] Stopping jboss.mq:service=InvocationLayer,type=OIL2
2005-01-14 13:21:08,041 DEBUG [org.jboss.mq.il.oil2.OIL2ServerILService] Stopped jboss.mq:service=InvocationLayer,type=OIL2
2005-01-14 13:21:08,041 DEBUG [org.jboss.mq.il.rmi.RMIServerILService] Stopping jboss.mq:service=InvocationLayer,type=RMI
2005-01-14 13:21:08,041 DEBUG [org.jboss.mq.il.rmi.RMIServerILService] Stopped jboss.mq:service=InvocationLayer,type=RMI
2005-01-14 13:21:08,041 DEBUG [org.jboss.mq.server.jmx.Invoker] Stopping jboss.mq:service=Invoker
2005-01-14 13:21:08,041 DEBUG [org.jboss.mq.server.jmx.Invoker] Stopped jboss.mq:service=Invoker
2005-01-14 13:21:08,041 DEBUG [org.jboss.mq.server.jmx.InterceptorLoader] Stopping jboss.mq:service=TracingInterceptor
2005-01-14 13:21:08,041 DEBUG [org.jboss.mq.server.jmx.InterceptorLoader] Stopped jboss.mq:service=TracingInterceptor
2005-01-14 13:21:22,557 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_ping, msgID: 12
2005-01-14 13:21:22,557 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.PingMsg4870944[msgType: m_ping, msgID: 12, error: null]
2005-01-14 13:21:22,557 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 21
2005-01-14 13:21:22,557 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : ping
2005-01-14 13:21:22,557 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : 1105734082557
2005-01-14 13:21:22,557 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.PingMsg26707490[msgType: m_pong, msgID: -2147483643, error: null]
2005-01-14 13:21:22,557 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.PingMsg26707490[msgType: m_pong, msgID: -2147483643, error: null]
2005-01-14 13:21:22,557 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : ping
2005-01-14 13:21:22,557 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 21
2005-01-14 13:21:22,557 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.PingMsg26707490[msgType: m_pong, msgID: -2147483643, error: null]
2005-01-14 13:21:40,275 DEBUG [org.jboss.mq.il.http.HTTPServerILService] Destroying jboss.mq:service=InvocationLayer,type=HTTP
2005-01-14 13:21:40,275 DEBUG [org.jboss.mq.il.http.HTTPServerILService] Destroyed jboss.mq:service=InvocationLayer,type=HTTP
2005-01-14 13:21:40,275 DEBUG [org.jboss.mq.il.jvm.JVMServerILService] Destroying jboss.mq:service=InvocationLayer,type=JVM
2005-01-14 13:21:40,275 DEBUG [org.jboss.mq.il.jvm.JVMServerILService] Destroyed jboss.mq:service=InvocationLayer,type=JVM
2005-01-14 13:21:40,275 DEBUG [org.jboss.mq.il.oil.OILServerILService] Destroying jboss.mq:service=InvocationLayer,type=OIL
2005-01-14 13:21:40,275 DEBUG [org.jboss.mq.il.oil.OILServerILService] Destroyed jboss.mq:service=InvocationLayer,type=OIL
2005-01-14 13:21:40,275 DEBUG [org.jboss.mq.il.oil2.OIL2ServerILService] Destroying jboss.mq:service=InvocationLayer,type=OIL2
2005-01-14 13:21:40,275 DEBUG [org.jboss.mq.il.oil2.OIL2ServerILService] Destroyed jboss.mq:service=InvocationLayer,type=OIL2
2005-01-14 13:21:40,275 DEBUG [org.jboss.mq.il.rmi.RMIServerILService] Destroying jboss.mq:service=InvocationLayer,type=RMI
2005-01-14 13:21:40,275 DEBUG [org.jboss.mq.il.rmi.RMIServerILService] Destroyed jboss.mq:service=InvocationLayer,type=RMI
2005-01-14 13:21:40,275 DEBUG [org.jboss.mq.il.uil2.UILServerILService] Destroying jboss.mq:service=InvocationLayer,type=UIL2
2005-01-14 13:21:40,275 DEBUG [org.jboss.mq.il.uil2.UILServerILService] Destroyed jboss.mq:service=InvocationLayer,type=UIL2
2005-01-14 13:21:40,275 DEBUG [org.jboss.mq.server.jmx.Invoker] Destroying jboss.mq:service=Invoker
2005-01-14 13:21:40,275 DEBUG [org.jboss.mq.server.jmx.Invoker] Destroyed jboss.mq:service=Invoker
2005-01-14 13:21:40,275 DEBUG [org.jboss.mq.server.jmx.InterceptorLoader] Destroying jboss.mq:service=TracingInterceptor
2005-01-14 13:21:40,275 DEBUG [org.jboss.mq.server.jmx.InterceptorLoader] Destroyed jboss.mq:service=TracingInterceptor
2005-01-14 13:22:22,570 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_ping, msgID: 13
2005-01-14 13:22:22,570 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.PingMsg23850238[msgType: m_ping, msgID: 13, error: null]
2005-01-14 13:22:22,570 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 21
2005-01-14 13:22:22,570 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : ping
2005-01-14 13:22:22,570 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : 1105734142570
2005-01-14 13:22:22,570 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.PingMsg9711883[msgType: m_pong, msgID: -2147483642, error: null]
2005-01-14 13:22:22,570 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.PingMsg9711883[msgType: m_pong, msgID: -2147483642, error: null]
2005-01-14 13:22:22,570 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.PingMsg9711883[msgType: m_pong, msgID: -2147483642, error: null]
2005-01-14 13:22:22,570 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : ping
2005-01-14 13:22:22,570 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 21
2005-01-14 13:23:22,584 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_ping, msgID: 14
2005-01-14 13:23:22,584 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.PingMsg10512406[msgType: m_ping, msgID: 14, error: null]
2005-01-14 13:23:22,584 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 21
2005-01-14 13:23:22,584 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : ping
2005-01-14 13:23:22,584 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : 1105734202584
2005-01-14 13:23:22,584 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.PingMsg15184847[msgType: m_pong, msgID: -2147483641, error: null]
2005-01-14 13:23:22,584 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.PingMsg15184847[msgType: m_pong, msgID: -2147483641, error: null]
2005-01-14 13:23:22,584 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.PingMsg15184847[msgType: m_pong, msgID: -2147483641, error: null]
2005-01-14 13:23:22,584 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : ping
2005-01-14 13:23:22,584 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 21
2005-01-14 13:24:22,598 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_ping, msgID: 15
2005-01-14 13:24:22,598 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.PingMsg29401207[msgType: m_ping, msgID: 15, error: null]
2005-01-14 13:24:22,598 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 21
2005-01-14 13:24:22,598 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : ping
2005-01-14 13:24:22,598 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : 1105734262598
2005-01-14 13:24:22,598 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.PingMsg23536403[msgType: m_pong, msgID: -2147483640, error: null]
2005-01-14 13:24:22,598 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.PingMsg23536403[msgType: m_pong, msgID: -2147483640, error: null]
2005-01-14 13:24:22,598 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : ping
2005-01-14 13:24:22,598 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 21
2005-01-14 13:24:22,598 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.PingMsg23536403[msgType: m_pong, msgID: -2147483640, error: null]
Client Log:
2005-01-14 13:17:22,221 55514 DEBUG [org.jboss.mq.referenceable.SpyConnectionFactoryObjectFactory] (main:) Extracting SpyConnectionFactory from reference
2005-01-14 13:17:22,267 55560 DEBUG [org.jboss.mq.referenceable.SpyConnectionFactoryObjectFactory] (main:) The GenericConnectionFactory is: GenericConnectionFactory[server=org.jboss.mq.il.uil2.UILServerIL@12d96f2 connectionProperties={UIL_ADDRESS_KEY=10.82.100.103, UIL_CHUNKSIZE_KEY=1000000, UIL_TCPNODELAY_KEY=yes, ClientILService=org.jboss.mq.il.uil2.UILClientILService, UIL_PORT_KEY=8093, UIL_BUFFERSIZE_KEY=2048, PingPeriod=60000}]
2005-01-14 13:17:22,267 55560 DEBUG [cdot.util.ServiceLocator] (main:) Client request. Returning local JNDI InitialContext.
2005-01-14 13:17:22,267 55560 DEBUG [cdot.util.ServiceLocator] (main:) Client request. Returning local JNDI InitialContext.
2005-01-14 13:17:22,283 55576 DEBUG [org.jboss.mq.referenceable.SpyDestinationObjectFactory] (main:) SpyDestinationObjectFactory->getObjectInstance()
2005-01-14 13:17:22,299 55592 DEBUG [org.jboss.mq.Connection] (main:) Setting the clockDaemon's thread factory
2005-01-14 13:17:22,314 55607 TRACE [org.jboss.mq.Connection] (main:) Connection Initializing userName=SYSTEM Connection@31275026[clientID=null rcvstate=STOPPED]
2005-01-14 13:17:22,314 55607 TRACE [org.jboss.mq.Connection] (main:) Getting the serverIL Connection@31275026[clientID=null rcvstate=STOPPED]
2005-01-14 13:17:22,314 55607 TRACE [org.jboss.mq.Connection] (main:) serverIL=org.jboss.mq.il.uil2.UILServerIL@8c436b Connection@31275026[clientID=null rcvstate=STOPPED]
2005-01-14 13:17:22,314 55607 TRACE [org.jboss.mq.Connection] (main:) Authenticating user SYSTEM Connection@31275026[clientID=null rcvstate=STOPPED]
2005-01-14 13:17:22,314 55607 TRACE [org.jboss.mq.il.uil2.UILServerIL] (main:) Connecting to : TOCARBONG1/10.82.100.103:8093
2005-01-14 13:17:22,314 55607 TRACE [org.jboss.mq.il.uil2.UILServerIL] (main:) Connecting with addr=10.82.100.103, port=8093, localAddr=null, localPort=0, socketFactory=javax.net.DefaultSocketFactory@3020ad, enableTcpNoDelay=true, bufferSize=2048, chunkSize=1000000
2005-01-14 13:17:22,346 55639 TRACE [org.jboss.mq.il.uil2.SocketManager] (main:) start called
java.lang.Exception: Start stack trace
at org.jboss.mq.il.uil2.SocketManager.start(SocketManager.java:94)
at org.jboss.mq.il.uil2.UILServerIL.createConnection(UILServerIL.java:626)
at org.jboss.mq.il.uil2.UILServerIL.getSocketMgr(UILServerIL.java:505)
at org.jboss.mq.il.uil2.UILServerIL.authenticate(UILServerIL.java:307)
at org.jboss.mq.Connection.authenticate(Connection.java:1153)
at org.jboss.mq.Connection.(Connection.java:248)
at org.jboss.mq.SpyConnection.(SpyConnection.java:55)
at org.jboss.mq.SpyConnectionFactory.createConnection(SpyConnectionFactory.java:108)
at org.jboss.mq.SpyConnectionFactory.createTopicConnection(SpyConnectionFactory.java:141)
at cdot.util.TopicReceiver.initSub(TopicReceiver.java:93)
at cdot.base.BaseJMSReceiver.init(BaseJMSReceiver.java:110)
at cdot.base.BaseJMSReceiver.init(BaseJMSReceiver.java:143)
at cdot.util.TopicTestHelper.start(TopicTestHelper.java:125)
at cdot.ctms.layer.delegate.test.RunJMSListeners.startUp(RunJMSListeners.java:85)
at cdot.ctms.layer.delegate.test.RunJMSListeners.main(RunJMSListeners.java:78)
2005-01-14 13:17:22,361 55654 TRACE [org.jboss.mq.il.uil2.SocketManager] (main:) Begin internalSendMessage, round-trip msg=org.jboss.mq.il.uil2.msgs.CheckUserMsg31706449[msgType: m_authenticate, msgID: 1, error: null]
2005-01-14 13:17:22,377 55670 DEBUG [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Begin ReadTask.run
2005-01-14 13:17:22,377 55670 DEBUG [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.WriteTask#2 client=10.82.100.103:8093:) Begin WriteTask.run
2005-01-14 13:17:22,377 55670 DEBUG [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.WriteTask#2 client=10.82.100.103:8093:) Created ObjectOutputStream
2005-01-14 13:17:22,377 55670 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.WriteTask#2 client=10.82.100.103:8093:) Write msg: org.jboss.mq.il.uil2.msgs.CheckUserMsg31706449[msgType: m_authenticate, msgID: 1, error: null]
2005-01-14 13:17:22,455 55748 DEBUG [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Created ObjectInputStream
2005-01-14 13:17:22,455 55748 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msgType: m_authenticate, msgID: 1
2005-01-14 13:17:22,455 55748 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Found replyMap msg: org.jboss.mq.il.uil2.msgs.CheckUserMsg31706449[msgType: m_authenticate, msgID: 1, error: null]
2005-01-14 13:17:22,455 55748 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msg reply: org.jboss.mq.il.uil2.msgs.CheckUserMsg31706449[msgType: m_authenticate, msgID: 1, error: null]
2005-01-14 13:17:22,455 55748 TRACE [org.jboss.mq.il.uil2.SocketManager] (main:) End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.CheckUserMsg31706449[msgType: m_authenticate, msgID: 1, error: null]
2005-01-14 13:17:22,455 55748 TRACE [org.jboss.mq.Connection] (main:) Ask for an id user=SYSTEM Connection@31275026[clientID=null rcvstate=STOPPED]
2005-01-14 13:17:22,455 55748 TRACE [org.jboss.mq.il.uil2.SocketManager] (main:) Begin internalSendMessage, round-trip msg=org.jboss.mq.il.uil2.msgs.CheckUserMsg6330655[msgType: m_checkUser, msgID: 2, error: null]
2005-01-14 13:17:22,455 55748 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.WriteTask#2 client=10.82.100.103:8093:) Write msg: org.jboss.mq.il.uil2.msgs.CheckUserMsg6330655[msgType: m_checkUser, msgID: 2, error: null]
2005-01-14 13:17:22,455 55748 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msgType: m_checkUser, msgID: 2
2005-01-14 13:17:22,455 55748 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Found replyMap msg: org.jboss.mq.il.uil2.msgs.CheckUserMsg6330655[msgType: m_checkUser, msgID: 2, error: null]
2005-01-14 13:17:22,455 55748 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msg reply: org.jboss.mq.il.uil2.msgs.CheckUserMsg6330655[msgType: m_checkUser, msgID: 2, error: null]
2005-01-14 13:17:22,455 55748 TRACE [org.jboss.mq.il.uil2.SocketManager] (main:) End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.CheckUserMsg6330655[msgType: m_checkUser, msgID: 2, error: null]
2005-01-14 13:17:22,455 55748 TRACE [org.jboss.mq.Connection] (main:) Starting the client il Connection@31275026[clientID=null rcvstate=STOPPED]
2005-01-14 13:17:22,455 55748 TRACE [org.jboss.mq.GenericConnectionFactory] (main:) Handing out ClientIL: org.jboss.mq.il.uil2.UILClientILService
2005-01-14 13:17:22,471 55764 DEBUG [org.jboss.mq.il.uil2.UILClientILService] (main:) Starting
2005-01-14 13:17:22,471 55764 TRACE [org.jboss.mq.Connection] (main:) Using client id org.jboss.mq.il.uil2.UILClientILService@10dc6b5 Connection@31275026[clientID=null rcvstate=STOPPED]
2005-01-14 13:17:22,471 55764 TRACE [org.jboss.mq.il.uil2.SocketManager] (main:) Begin internalSendMessage, round-trip msg=org.jboss.mq.il.uil2.msgs.ConnectionTokenMsg24166053[msgType: m_setSpyDistributedConnection, msgID: 3, error: null]
2005-01-14 13:17:22,471 55764 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.WriteTask#2 client=10.82.100.103:8093:) Write msg: org.jboss.mq.il.uil2.msgs.ConnectionTokenMsg24166053[msgType: m_setSpyDistributedConnection, msgID: 3, error: null]
2005-01-14 13:17:22,486 55779 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msgType: m_setSpyDistributedConnection, msgID: 3
2005-01-14 13:17:22,486 55779 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Found replyMap msg: org.jboss.mq.il.uil2.msgs.ConnectionTokenMsg24166053[msgType: m_setSpyDistributedConnection, msgID: 3, error: null]
2005-01-14 13:17:22,486 55779 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msg reply: org.jboss.mq.il.uil2.msgs.ConnectionTokenMsg24166053[msgType: m_setSpyDistributedConnection, msgID: 3, error: null]
2005-01-14 13:17:22,486 55779 TRACE [org.jboss.mq.il.uil2.SocketManager] (main:) End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.ConnectionTokenMsg24166053[msgType: m_setSpyDistributedConnection, msgID: 3, error: null]
2005-01-14 13:17:22,486 55779 TRACE [org.jboss.mq.Connection] (main:) Creating XAResourceManager Connection@31275026[token=ConnectionToken:null/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STOPPED]
2005-01-14 13:17:22,486 55779 TRACE [org.jboss.mq.Connection] (main:) Starting the ping thread Connection@31275026[token=ConnectionToken:null/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STOPPED]
2005-01-14 13:17:22,502 55795 TRACE [org.jboss.mq.Connection] (main:) Connection establishment successful Connection@31275026[token=ConnectionToken:null/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STOPPED]
2005-01-14 13:17:22,502 55795 TRACE [org.jboss.mq.Connection] (main:) Checking clientID=null Connection@31275026[token=ConnectionToken:null/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STOPPED]
2005-01-14 13:17:22,502 55795 TRACE [org.jboss.mq.Connection] (main:) Ask for an id Connection@31275026[token=ConnectionToken:null/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STOPPED]
2005-01-14 13:17:22,502 55795 TRACE [org.jboss.mq.il.uil2.SocketManager] (main:) Begin internalSendMessage, round-trip msg=org.jboss.mq.il.uil2.msgs.GetIDMsg25919971[msgType: m_getID, msgID: 4, error: null]
2005-01-14 13:17:22,502 55795 TRACE [org.jboss.mq.Connection] (Connection Monitor Thread:) PING 1105733842502 Connection@31275026[token=ConnectionToken:null/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STOPPED]
2005-01-14 13:17:22,502 55795 TRACE [org.jboss.mq.il.uil2.SocketManager] (Connection Monitor Thread:) Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.PingMsg27979955[msgType: m_ping, msgID: 5, error: null]
2005-01-14 13:17:22,502 55795 TRACE [org.jboss.mq.il.uil2.SocketManager] (Connection Monitor Thread:) End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.PingMsg27979955[msgType: m_ping, msgID: 5, error: null]
2005-01-14 13:17:22,502 55795 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.WriteTask#2 client=10.82.100.103:8093:) Write msg: org.jboss.mq.il.uil2.msgs.GetIDMsg25919971[msgType: m_getID, msgID: 4, error: null]
2005-01-14 13:17:22,502 55795 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msgType: m_getID, msgID: 4
2005-01-14 13:17:22,502 55795 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Found replyMap msg: org.jboss.mq.il.uil2.msgs.GetIDMsg25919971[msgType: m_getID, msgID: 4, error: null]
2005-01-14 13:17:22,502 55795 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msg reply: org.jboss.mq.il.uil2.msgs.GetIDMsg25919971[msgType: m_getID, msgID: 4, error: null]
2005-01-14 13:17:22,502 55795 TRACE [org.jboss.mq.il.uil2.SocketManager] (main:) End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.GetIDMsg25919971[msgType: m_getID, msgID: 4, error: null]
2005-01-14 13:17:22,502 55795 TRACE [org.jboss.mq.Connection] (main:) ClientID established Connection@31275026[token=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STOPPED]
2005-01-14 13:17:22,517 55810 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.WriteTask#2 client=10.82.100.103:8093:) Write msg: org.jboss.mq.il.uil2.msgs.PingMsg27979955[msgType: m_ping, msgID: 5, error: null]
2005-01-14 13:17:22,533 55826 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msgType: m_pong, msgID: -2147483647
2005-01-14 13:17:22,533 55826 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read new msg: org.jboss.mq.il.uil2.msgs.PingMsg18179071[msgType: m_pong, msgID: -2147483647, error: null]
2005-01-14 13:17:22,549 55842 TRACE [org.jboss.mq.SpySession] (main:) New session SpySession@19267322[tx=false ack=AUTO txid=null RUNNING connection=Connection@31275026[token=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STOPPED]]
2005-01-14 13:17:22,549 55842 TRACE [org.jboss.mq.SpyMessageConsumer] (main:) New message consumer SpyMessageConsumer@25616143[sub=Subscription[subId=0 destination=TOPIC.AlarmTopic messageSelector=null NoLocal Create] listening=false receiving=false sessionConsumer=false waitingForMessage=false messages=0 session=SpySession@19267322[tx=false ack=AUTO txid=null RUNNING connection=Connection@31275026[token=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STOPPED]]]
2005-01-14 13:17:22,549 55842 TRACE [org.jboss.mq.SpySession] (main:) Adding consumer SpyMessageConsumer@25616143[sub=Subscription[subId=0 destination=TOPIC.AlarmTopic messageSelector=null NoLocal Create] listening=false receiving=false sessionConsumer=false waitingForMessage=false messages=0 session=SpySession@19267322[tx=false ack=AUTO txid=null RUNNING connection=Connection@31275026[token=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STOPPED]]]
2005-01-14 13:17:22,549 55842 TRACE [org.jboss.mq.il.uil2.UILClientILService] (Thread-1:) Begin handleMsg, msgType: 24
2005-01-14 13:17:22,549 55842 TRACE [org.jboss.mq.Connection] (Thread-1:) PONG serverTime=1105733842533 Connection@31275026[token=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STOPPED]
2005-01-14 13:17:22,549 55842 TRACE [org.jboss.mq.il.uil2.UILClientILService] (Thread-1:) End handleMsg
2005-01-14 13:17:22,549 55842 TRACE [org.jboss.mq.Connection] (main:) addConsumer sub=Subscription[subId=-2147483648connection=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 destination=TOPIC.AlarmTopic messageSelector=null NoLocal Create]
2005-01-14 13:17:22,549 55842 TRACE [org.jboss.mq.il.uil2.SocketManager] (main:) Begin internalSendMessage, round-trip msg=org.jboss.mq.il.uil2.msgs.SubscribeMsg12864392[msgType: m_subscribe, msgID: 6, error: null]
2005-01-14 13:17:22,549 55842 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.WriteTask#2 client=10.82.100.103:8093:) Write msg: org.jboss.mq.il.uil2.msgs.SubscribeMsg12864392[msgType: m_subscribe, msgID: 6, error: null]
2005-01-14 13:17:22,892 56185 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msgType: m_subscribe, msgID: 6
2005-01-14 13:17:22,892 56185 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Found replyMap msg: org.jboss.mq.il.uil2.msgs.SubscribeMsg12864392[msgType: m_subscribe, msgID: 6, error: null]
2005-01-14 13:17:22,892 56185 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msg reply: org.jboss.mq.il.uil2.msgs.SubscribeMsg12864392[msgType: m_subscribe, msgID: 6, error: null]
2005-01-14 13:17:22,892 56185 TRACE [org.jboss.mq.il.uil2.SocketManager] (main:) End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.SubscribeMsg12864392[msgType: m_subscribe, msgID: 6, error: null]
2005-01-14 13:17:22,892 56185 TRACE [org.jboss.mq.SpyMessageConsumer] (main:) Set message listener=cdot.util.TopicTestHelper@5dcec6 old listener=null SpyMessageConsumer@25616143[sub=Subscription[subId=-2147483648connection=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 destination=TOPIC.AlarmTopic messageSelector=null NoLocal Create] listening=false receiving=false sessionConsumer=false waitingForMessage=false messages=0 session=SpySession@19267322[tx=false ack=AUTO txid=null RUNNING connection=Connection@31275026[token=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STOPPED]]]
2005-01-14 13:17:22,892 56185 TRACE [org.jboss.mq.Connection] (main:) Starting connection Connection@31275026[token=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STARTED]
2005-01-14 13:17:22,892 56185 TRACE [org.jboss.mq.il.uil2.SocketManager] (main:) Begin internalSendMessage, round-trip msg=org.jboss.mq.il.uil2.msgs.EnableConnectionMsg11688861[msgType: m_setEnabled, msgID: 7, error: null]
2005-01-14 13:17:22,892 56185 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.WriteTask#2 client=10.82.100.103:8093:) Write msg: org.jboss.mq.il.uil2.msgs.EnableConnectionMsg11688861[msgType: m_setEnabled, msgID: 7, error: null]
2005-01-14 13:17:22,892 56185 TRACE [org.jboss.mq.Connection] (MessageListenerThread - AlarmTopic:) Receive subscription=Subscription[subId=-2147483648connection=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 destination=TOPIC.AlarmTopic messageSelector=null NoLocal Create] wait=0
2005-01-14 13:17:22,908 56201 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msgType: m_setEnabled, msgID: 7
2005-01-14 13:17:22,908 56201 TRACE [org.jboss.mq.il.uil2.SocketManager] (MessageListenerThread - AlarmTopic:) Begin internalSendMessage, round-trip msg=org.jboss.mq.il.uil2.msgs.ReceiveMsg5165021[msgType: m_receive, msgID: 8, error: null]
2005-01-14 13:17:22,908 56201 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Found replyMap msg: org.jboss.mq.il.uil2.msgs.EnableConnectionMsg11688861[msgType: m_setEnabled, msgID: 7, error: null]
2005-01-14 13:17:22,908 56201 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.WriteTask#2 client=10.82.100.103:8093:) Write msg: org.jboss.mq.il.uil2.msgs.ReceiveMsg5165021[msgType: m_receive, msgID: 8, error: null]
2005-01-14 13:17:22,908 56201 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msg reply: org.jboss.mq.il.uil2.msgs.EnableConnectionMsg11688861[msgType: m_setEnabled, msgID: 7, error: null]
2005-01-14 13:17:22,908 56201 TRACE [org.jboss.mq.il.uil2.SocketManager] (main:) End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.EnableConnectionMsg11688861[msgType: m_setEnabled, msgID: 7, error: null]
2005-01-14 13:17:22,908 56201 INFO [cdot.util.TopicReceiver] (main:) Connected! Ready to receive messages from Topic: topic/AlarmTopic
2005-01-14 13:17:22,908 56201 INFO [cdot.util.TopicReceiver] (main:) Connected! Ready to receive messages from Topic: topic/AlarmTopic
2005-01-14 13:17:22,908 56201 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msgType: m_receive, msgID: 8
2005-01-14 13:17:22,908 56201 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Found replyMap msg: org.jboss.mq.il.uil2.msgs.ReceiveMsg5165021[msgType: m_receive, msgID: 8, error: null]
2005-01-14 13:17:22,908 56201 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msg reply: org.jboss.mq.il.uil2.msgs.ReceiveMsg5165021[msgType: m_receive, msgID: 8, error: null]
2005-01-14 13:17:22,908 56201 TRACE [org.jboss.mq.il.uil2.SocketManager] (MessageListenerThread - AlarmTopic:) End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.ReceiveMsg5165021[msgType: m_receive, msgID: 8, error: null]
2005-01-14 13:17:22,908 56201 TRACE [org.jboss.mq.SpyMessageConsumer] (MessageListenerThread - AlarmTopic:) waiting in run() SpyMessageConsumer@25616143[sub=Subscription[subId=-2147483648connection=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 destination=TOPIC.AlarmTopic messageSelector=null NoLocal Create] listening=true receiving=false sessionConsumer=false waitingForMessage=true messages=0 thread=Thread[MessageListenerThread - AlarmTopic,5,main] listener=cdot.util.TopicTestHelper@5dcec6 session=SpySession@19267322[tx=false ack=AUTO txid=null RUNNING connection=Connection@31275026[token=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STARTED]]]
2005-01-14 13:18:22,515 115808 TRACE [org.jboss.mq.Connection] (Connection Monitor Thread:) PING 1105733902515 Connection@31275026[token=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STARTED]
2005-01-14 13:18:22,515 115808 TRACE [org.jboss.mq.il.uil2.SocketManager] (Connection Monitor Thread:) Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.PingMsg12621140[msgType: m_ping, msgID: 9, error: null]
2005-01-14 13:18:22,515 115808 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.WriteTask#2 client=10.82.100.103:8093:) Write msg: org.jboss.mq.il.uil2.msgs.PingMsg12621140[msgType: m_ping, msgID: 9, error: null]
2005-01-14 13:18:22,515 115808 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msgType: m_pong, msgID: -2147483646
2005-01-14 13:18:22,515 115808 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read new msg: org.jboss.mq.il.uil2.msgs.PingMsg25948274[msgType: m_pong, msgID: -2147483646, error: null]
2005-01-14 13:18:22,515 115808 TRACE [org.jboss.mq.il.uil2.UILClientILService] (Thread-1:) Begin handleMsg, msgType: 24
2005-01-14 13:18:22,515 115808 TRACE [org.jboss.mq.Connection] (Thread-1:) PONG serverTime=1105733902515 Connection@31275026[token=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STARTED]
2005-01-14 13:18:22,515 115808 TRACE [org.jboss.mq.il.uil2.UILClientILService] (Thread-1:) End handleMsg
2005-01-14 13:18:22,515 115808 TRACE [org.jboss.mq.il.uil2.SocketManager] (Connection Monitor Thread:) End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.PingMsg12621140[msgType: m_ping, msgID: 9, error: null]
2005-01-14 13:19:22,529 175822 TRACE [org.jboss.mq.Connection] (Connection Monitor Thread:) PING 1105733962529 Connection@31275026[token=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STARTED]
2005-01-14 13:19:22,529 175822 TRACE [org.jboss.mq.il.uil2.SocketManager] (Connection Monitor Thread:) Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.PingMsg5699121[msgType: m_ping, msgID: 10, error: null]
2005-01-14 13:19:22,529 175822 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.WriteTask#2 client=10.82.100.103:8093:) Write msg: org.jboss.mq.il.uil2.msgs.PingMsg5699121[msgType: m_ping, msgID: 10, error: null]
2005-01-14 13:19:22,529 175822 TRACE [org.jboss.mq.il.uil2.SocketManager] (Connection Monitor Thread:) End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.PingMsg5699121[msgType: m_ping, msgID: 10, error: null]
2005-01-14 13:19:22,529 175822 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msgType: m_pong, msgID: -2147483645
2005-01-14 13:19:22,529 175822 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read new msg: org.jboss.mq.il.uil2.msgs.PingMsg20785379[msgType: m_pong, msgID: -2147483645, error: null]
2005-01-14 13:19:22,529 175822 TRACE [org.jboss.mq.il.uil2.UILClientILService] (Thread-2:) Begin handleMsg, msgType: 24
2005-01-14 13:19:22,529 175822 TRACE [org.jboss.mq.Connection] (Thread-2:) PONG serverTime=1105733962529 Connection@31275026[token=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STARTED]
2005-01-14 13:19:22,529 175822 TRACE [org.jboss.mq.il.uil2.UILClientILService] (Thread-2:) End handleMsg
2005-01-14 13:20:22,543 235836 TRACE [org.jboss.mq.Connection] (Connection Monitor Thread:) PING 1105734022543 Connection@31275026[token=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STARTED]
2005-01-14 13:20:22,543 235836 TRACE [org.jboss.mq.il.uil2.SocketManager] (Connection Monitor Thread:) Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.PingMsg20290587[msgType: m_ping, msgID: 11, error: null]
2005-01-14 13:20:22,543 235836 TRACE [org.jboss.mq.il.uil2.SocketManager] (Connection Monitor Thread:) End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.PingMsg20290587[msgType: m_ping, msgID: 11, error: null]
2005-01-14 13:20:22,543 235836 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.WriteTask#2 client=10.82.100.103:8093:) Write msg: org.jboss.mq.il.uil2.msgs.PingMsg20290587[msgType: m_ping, msgID: 11, error: null]
2005-01-14 13:20:22,543 235836 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msgType: m_pong, msgID: -2147483644
2005-01-14 13:20:22,543 235836 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read new msg: org.jboss.mq.il.uil2.msgs.PingMsg10217370[msgType: m_pong, msgID: -2147483644, error: null]
2005-01-14 13:20:22,543 235836 TRACE [org.jboss.mq.il.uil2.UILClientILService] (Thread-3:) Begin handleMsg, msgType: 24
2005-01-14 13:20:22,543 235836 TRACE [org.jboss.mq.Connection] (Thread-3:) PONG serverTime=1105734022543 Connection@31275026[token=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STARTED]
2005-01-14 13:20:22,543 235836 TRACE [org.jboss.mq.il.uil2.UILClientILService] (Thread-3:) End handleMsg
2005-01-14 13:21:22,557 295850 TRACE [org.jboss.mq.Connection] (Connection Monitor Thread:) PING 1105734082557 Connection@31275026[token=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STARTED]
2005-01-14 13:21:22,557 295850 TRACE [org.jboss.mq.il.uil2.SocketManager] (Connection Monitor Thread:) Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.PingMsg6278953[msgType: m_ping, msgID: 12, error: null]
2005-01-14 13:21:22,557 295850 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.WriteTask#2 client=10.82.100.103:8093:) Write msg: org.jboss.mq.il.uil2.msgs.PingMsg6278953[msgType: m_ping, msgID: 12, error: null]
2005-01-14 13:21:22,557 295850 TRACE [org.jboss.mq.il.uil2.SocketManager] (Connection Monitor Thread:) End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.PingMsg6278953[msgType: m_ping, msgID: 12, error: null]
2005-01-14 13:21:22,557 295850 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read msgType: m_pong, msgID: -2147483643
2005-01-14 13:21:22,557 295850 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.ReadTask#1 client=10.82.100.103:8093:) Read new msg: org.jboss.mq.il.uil2.msgs.PingMsg1683934[msgType: m_pong, msgID: -2147483643, error: null]
2005-01-14 13:21:22,557 295850 TRACE [org.jboss.mq.il.uil2.UILClientILService] (Thread-4:) Begin handleMsg, msgType: 24
2005-01-14 13:21:22,557 295850 TRACE [org.jboss.mq.Connection] (Thread-4:) PONG serverTime=1105734082557 Connection@31275026[token=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STARTED]
2005-01-14 13:21:22,557 295850 TRACE [org.jboss.mq.il.uil2.UILClientILService] (Thread-4:) End handleMsg
2005-01-14 13:22:22,570 355863 TRACE [org.jboss.mq.Connection] (Connection Monitor Thread:) PING 1105734142570 Connection@31275026[token=ConnectionToken:ID:1/95e8ba4862c0bee5320ce4454ee559c8 rcvstate=STARTED]
2005-01-14 13:22:22,570 355863 TRACE [org.jboss.mq.il.uil2.SocketManager] (Connection Monitor Thread:) Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.PingMsg4839036[msgType: m_ping, msgID: 13, error: null]
2005-01-14 13:22:22,570 355863 TRACE [org.jboss.mq.il.uil2.SocketManager] (UIL2.SocketManager.WriteTask#2 client=10.82.100.103:8093:) Write msg: org.jboss.mq.il.uil2.msgs.PingMsg4839036[msgType: m_ping, msgID: 13, error: null]
2005-01-14 13:22:22,570 355863 TRACE [org.jboss.mq.il.uil2.Soc