Welcome!

Welcome to the official BlackBerry Support Community Forums.

This is your resource to discuss support topics with your peers, and learn from each other.

inside custom component

BlackBerry Web Services

Reply
New Contributor
marevens
Posts: 5
Registered: ‎03-22-2011
My Device: none

Problems with custom java app running on device

I have a custom java application published on a Blackberry 9800 smartphone via Blackberry Enterprise Server Express. My java application is sending messages through BES (via mds) and to our webserver. This seems to work fine, for a while, but afther a while the messages from java app doesn’t get through. It might be an issue with the java code, or my environment, or both.

 

I see in log (MDAT), that everytime I try to send a message from java app, the following log text is produced: “SCM, Channel is closed” and “IPPP, User has been logged off”. Please browse through my code and logs, and give me advice on how to troubleshoot or fix this behavior.

 

MDAT-logfile:

<2011-08-19 11:06:57.382 CEST>:[1313]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Receiving, VERSION = 1, COMMAND = RECEIVE, TAG = 7826, SIZE = 714>

<2011-08-19 11:06:57.382 CEST>:[1314]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = RECEIVE, TAG = 7826, SIZE = 10>

<2011-08-19 11:06:57.382 CEST>:[1315]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = Receiving, TAG = -166703508, DEVICEPIN = 276502fe, USERID = u5, VERSION = 16, CONNECTIONID = 2124635195, SEQUENCE = 0, TYPE = CONNECTION-REQUEST, CONNECTIONHANDLER = defaulthandlernio, PROTOCOL = tcpchannel, PARAMETERS = [lvkdb01:80], SIZE = 553>

<2011-08-19 11:06:57.382 CEST>:[1316]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = CreatedReceivingQueue, USERID:CONNECTIONID = u5:2124635195, ReceivingQueueSize = 1>

<2011-08-19 11:06:57.382 CEST>:[1317]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = StartExecuting, TAG = -166703508, DEVICEPIN = 276502fe, USERID = u5, VERSION = 16, CONNECTIONID = 2124635195, SEQUENCE = 0, TYPE = CONNECTION-REQUEST, CONNECTIONHANDLER = defaulthandlernio, PROTOCOL = tcpchannel, PARAMETERS = [lvkdb01:80], SIZE = 553>

<2011-08-19 11:06:57.382 CEST>:[1318]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = EndExecuting, TAG = -166703508, DEVICEPIN = 276502fe, USERID = u5, VERSION = 16, CONNECTIONID = 2124635195, SEQUENCE = 0, TYPE = CONNECTION-REQUEST, CONNECTIONHANDLER = defaulthandlernio, PROTOCOL = tcpchannel, PARAMETERS = [lvkdb01:80], SIZE = 553>

<2011-08-19 11:06:57.382 CEST>:[1319]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, SocketChannel callback starting:u5>

<2011-08-19 11:06:57.382 CEST>:[1320]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Socket channel established; address:lvkdb01:80; time:0 ms>

<2011-08-19 11:06:57.382 CEST>:[1321]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, User has been logged off:lvkdb01:80:u5:2124635195;>

<2011-08-19 11:06:57.382 CEST>:[1322]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Device connections: AVG latency (msecs)0>

<2011-08-19 11:06:57.382 CEST>:[1323]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = IPPP, DEVICEPIN = 276502fe, DOMAINNAME = lvkdb01, CONNECTION_TYPE = DEVICE_CONN, ConnectionId = 2124635195, DURATION(ms) = 0, MFH_KBytes = 0,54, MTH_KBytes = 0, MFH_PACKET_COUNT = 1, MTH_PACKET_COUNT = 0>

<2011-08-19 11:06:57.382 CEST>:[1324]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, defaulthandlernio: ipppIoThreadPool-12 stopped>

<2011-08-19 11:06:57.382 CEST>:[1325]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, defaulthandlernio: ipppIoThreadPool-12 cleaned up>

<2011-08-19 11:06:57.382 CEST>:[1326]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = CreatedSendingQueue, DEVICEPIN = 276502fe, USERID = u5>

<2011-08-19 11:06:57.382 CEST>:[1327]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = Sending, TAG = 1379991581, DEVICEPIN = 276502fe, USERID = u5, VERSION = 16, CONNECTIONID = 2124635195, SEQUENCE = 0, TYPE = DISCONNECT-ORDER, SIZE = 588>

<2011-08-19 11:06:57.397 CEST>:[1328]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Channel is closed>

<2011-08-19 11:06:57.397 CEST>:[1329]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = RemovedReceivingQueue, USERID:CONNECTIONID = u5:2124635195, ReceivingQueueSize = 0>

<2011-08-19 11:06:57.397 CEST>:[1330]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Channel is closed>

<2011-08-19 11:06:57.397 CEST>:[1331]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = SEND, TAG = 1379991581, SIZE = 730>

<2011-08-19 11:06:57.897 CEST>:[1332]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Receiving, VERSION = 1, COMMAND = STATUS, TAG = 1379991581, SIZE = 10, STATE = DELIVERED>

<2011-08-19 11:06:57.897 CEST>:[1333]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = Notification, TAG = 1379991581, STATE = DELIVERED>

<2011-08-19 11:06:58.975 CEST>:[1334]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = RemovedSendingQueue, DEVICEPIN = 276502fe, USERID = u5>

 

Here is the code which is doing the soap call:

private boolean sendStatusMessage(String phoneNumber, String status) {

            try {

            String body = "<soapenv:Envelope xmlns:soapenv=\"http://schemas.xmlsoap.org/soap/envelope/\" xmlns:i3w=\"http://I3WebAction\">" + "<soapenv:Header/>" + "<soapenv:Body>"

            + "<i3w:I3SetMobileStatus><i3w:smileytongue:_Status>" + status + "</i3w:smileytongue:_Status><i3w:smileytongue:_PhoneNumber>" + phoneNumber + "</i3w:smileytongue:_PhoneNumber>"

            + "</i3w:I3SetMobileStatus></soapenv:Body></soapenv:Envelope>";

           

            String URL = "socket://" + soapServer + ":" + port + ";deviceside=false";

            StreamConnection conn = null;

            conn = (StreamConnection)Connector.open(URL);

           

            OutputStream output = conn.openOutputStream();

            OutputStreamWriter writer = new OutputStreamWriter(output);

            writer.write("POST /SOAPListener/I3SOAPISAPIU.dll HTTP/1.1\r\n");

            writer.write("Accept-Encoding: gzip,deflate\r\n");

            writer.write("Content-Type: text/xml;charset=UTF-8\r\n");

            writer.write("SOAPAction: \"http://I3WebAction/I3SetMobileStatus\"\r\n");

            writer.write("User-Agent: Jakarta Commons-HttpClient/3.1\r\n");

            writer.write("Host: lvkdb01\r\n");

            writer.write("Content-Length: " + body.length() + "\r\n");

            writer.write("\r\n");

            writer.write(body);

                       

            writer.flush();

            } catch (Exception e) {

                  Dialog.alert(e.getMessage());

                  return false;

            }

            return true;

      }

 

 

BBAS-logfile:

JobManagerBean.processDeliveredTasksTimeoutLocal

(08/19 11:10:05:069):{WorkManager(2)-113} [com.rim.bes.bas.softwareconfigurationmanager.SoftwareConfigurationManagerUtilities] [WARN] [BBAS-2038] {u=SystemUser, t=3800} retrieveExternalXMLFile could not find a valid combination of basic authentication user identifer and password traits.

(08/19 11:10:05:085):{WorkManager(2)-114} [com.rim.bes.bas.reconciliationmanager.ReconciliationManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=3823} Enter bean ReconciliationManagerBean.restartEventTaskExecutionIfRequiredInNewTransactionLocal

(08/19 11:10:05:085):{WorkManager(2)-114} [com.rim.bes.bas.jobmanager.JobManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=3824} Enter bean JobManagerBean.findFirstTaskInStateLocal

(08/19 11:10:05:100):{WorkManager(2)-114} [com.rim.bes.bas.jobmanager.JobManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=3824} Exit bean JobManagerBean.findFirstTaskInStateLocal

(08/19 11:10:05:100):{WorkManager(2)-114} [com.rim.bes.bas.jobmanager.JobManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=3825} Enter bean JobManagerBean.findFirstTaskInStateLocal

(08/19 11:10:05:100):{WorkManager(2)-114} [com.rim.bes.bas.jobmanager.JobManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=3825} Exit bean JobManagerBean.findFirstTaskInStateLocal

(08/19 11:10:05:100):{WorkManager(2)-114} [com.rim.bes.bas.jobmanager.JobManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=3826} Enter bean JobManagerBean.findFirstTaskInStateLocal

(08/19 11:10:05:100):{WorkManager(2)-114} [com.rim.bes.bas.jobmanager.JobManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=3826} Exit bean JobManagerBean.findFirstTaskInStateLocal

(08/19 11:10:05:100):{WorkManager(2)-114} [com.rim.bes.bas.reconciliationmanager.ReconciliationManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=3823} Exit bean ReconciliationManagerBean.restartEventTaskExecutionIfRequiredInNewTransactionLocal

(08/19 11:10:05:100):{WorkManager(2)-114} [com.rim.bes.bas.reconciliationmanager.ReconciliationManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=3827} Enter bean ReconciliationManagerBean.setCompletedEventProcessingJobStateToSuccessInNewTransactionLocal

(08/19 11:10:05:100):{WorkManager(2)-114} [com.rim.bes.bas.jobmanager.JobManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=3828} Enter bean JobManagerBean.updateJobStateByObjectTypeLocal

(08/19 11:10:05:116):{WorkManager(2)-115} [com.rim.bes.bas.jobmanager.JobManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=3831} Enter bean JobManagerBean.processDeliveredTasksTimeoutLocal

(08/19 11:10:05:116):{WorkManager(2)-115} [com.rim.bes.bas.jobmanager.JobManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=3831} Exit bean JobManagerBean.processDeliveredTasksTimeoutLocal

(08/19 11:10:05:132):{WorkManager(2)-114} [com.rim.bes.bas.jobmanager.JobManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=3828} Exit bean JobManagerBean.updateJobStateByObjectTypeLocal

(08/19 11:10:05:132):{WorkManager(2)-114} [com.rim.bes.bas.reconciliationmanager.ReconciliationManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=3827} Exit bean ReconciliationManagerBean.setCompletedEventProcessingJobStateToSuccessInNewTransactionLocal

(08/19 11:10:36:741):{WorkManager(2)-113} [com.rim.bes.bas.softwareconfigurationmanager.SoftwareConfigurationManagerUtilities] [WARN] [BBAS-2038] {u=SystemUser, t=3800} retrieveExternalXMLFile could not find a valid combination of basic authentication user identifer and password traits.

(08/19 11:10:57:757):{WorkManager(2)-113} [com.rim.bes.bas.softwareconfigurationmanager.SoftwareConfigurationManagerUtilities] [WARN] [BBAS-2038] {u=SystemUser, t=3800} retrieveExternalXMLFile Exception while trying to retrieve a file from web com.rim.bes.bas.windowsaccess.windowshttpaccessor.WindowsHTTPAccessorException: Nested exception: 'WinHTTP exception occurred in function: JWinHTTP::getResultFile, Url: https://www.blackberry.com/Desktop/Download/XML/Device.xml, Error: 0 - The operation completed successfully.  [ GetResultFile no HTTP response results after all proxies]'

 

*** Start of original stack trace ***

 

com.rim.bes.bas.windowsaccess.windowshttpaccessor.WindowsHTTPAccessorException: Nested exception: 'WinHTTP exception occurred in function: JWinHTTP::getResultFile, Url: https://www.blackberry.com/Desktop/Download/XML/Device.xml, Error: 0 - The operation completed successfully.  [ GetResultFile no HTTP response results after all proxies]'

                at com.rim.bes.bas.windowsaccess.windowshttpaccessor.WindowsHTTPAccessorUtilities.retrieveFileFromWeb(WindowsHTTPAccessorUtilities.java:130)

                at com.rim.bes.bas.softwareconfigurationmanager.SoftwareConfigurationManagerUtilities.retrieveExternalXMLFile(SoftwareConfigurationManagerUtilities.java:4438)

                at com.rim.bes.bas.softwareconfigurationmanager.SoftwareConfigurationManagerBean._retrieveExternalXML(SoftwareConfigurationManagerBean.java:9718)

                at com.rim.bes.bas.softwareconfigurationmanager.SoftwareConfigurationManagerBean.retrieveExternalXMLLocal(SoftwareConfigurationManagerBean.java:9649)

                at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

                at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:112)

                at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:166)

                at com.rim.bes.bas.BASCustomAnnotationHandlerInterceptor.execute(BASCustomAnnotationHandlerInterceptor.java:106)

                at sun.reflect.GeneratedMethodAccessor192.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:118)

                at org.jboss.ejb3.interceptor.EJB3InterceptorsInterceptor.invoke(EJB3InterceptorsInterceptor.java:63)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.entity.TransactionScopedEntityManagerInterceptor.invoke(TransactionScopedEntityManagerInterceptor.java:54)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.AllowedOperationsInterceptor.invoke(AllowedOperationsInterceptor.java:47)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at com.rim.bes.bas.BASApplicationDeadlockInterceptor.invoke(BASApplicationDeadlockInterceptor.java:99)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.tx.TxPolicy.invokeInCallerTx(TxPolicy.java:126)

                at org.jboss.aspects.tx.TxInterceptor$Required.invoke(TxInterceptor.java:195)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.tx.TxPropagationInterceptor.invoke(TxPropagationInterceptor.java:94)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at com.rim.bes.bas.BASExceptionStackInterceptor.invoke(BASExceptionStackInterceptor.java:85)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:70)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.remoting.ReplicantsManagerInterceptor.invoke(ReplicantsManagerInterceptor.java:51)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.security.RoleBasedAuthorizationInterceptor.invoke(RoleBasedAuthorizationInterceptor.java:166)

                at org.jboss.ejb3.security.RoleBasedAuthorizationInterceptor.invoke(RoleBasedAuthorizationInterceptor.java:108)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.security.AuthenticationInterceptor.invoke(AuthenticationInterceptor.java:77)

                at org.jboss.ejb3.security.Ejb3AuthenticationInterceptor.invoke(Ejb3AuthenticationInterceptor.java:139)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at com.rim.bes.bas.BASRemoteExceptionHandlerInterceptor.invoke(BASRemoteExceptionHandlerInterceptor.java:128)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.ENCPropagationInterceptor.invoke(ENCPropagationInterceptor.java:46)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.asynchronous.AsynchronousInterceptor.invoke(AsynchronousInterceptor.java:106)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.stateless.StatelessContainer.localInvoke(StatelessContainer.java:217)

                at org.jboss.ejb3.stateless.StatelessContainer.localInvoke(StatelessContainer.java:187)

                at org.jboss.ejb3.stateless.StatelessLocalProxy.invoke(StatelessLocalProxy.java:81)

                at $Proxy623.retrieveExternalXMLLocal(Unknown Source)

                at com.rim.bes.bas.softwareconfigurationmanager.ExternalXMLTimerHandler.execute(ExternalXMLTimerHandler.java:79)

                at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

                at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at com.rim.bes.bas.timermanager.TimerManagerBean._invokeExecuteMethod(TimerManagerBean.java:3148)

                at com.rim.bes.bas.timermanager.TimerManagerBean.executeTimerHandlerAsyncMethodLocal(TimerManagerBean.java:2847)

                at sun.reflect.GeneratedMethodAccessor732.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:112)

                at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:166)

                at com.rim.bes.bas.BASCustomAnnotationHandlerInterceptor.execute(BASCustomAnnotationHandlerInterceptor.java:106)

                at sun.reflect.GeneratedMethodAccessor192.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:118)

                at org.jboss.ejb3.interceptor.EJB3InterceptorsInterceptor.invoke(EJB3InterceptorsInterceptor.java:63)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.entity.TransactionScopedEntityManagerInterceptor.invoke(TransactionScopedEntityManagerInterceptor.java:54)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.AllowedOperationsInterceptor.invoke(AllowedOperationsInterceptor.java:47)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at com.rim.bes.bas.BASApplicationDeadlockInterceptor.invoke(BASApplicationDeadlockInterceptor.java:99)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.tx.TxPolicy.invokeInCallerTx(TxPolicy.java:126)

                at org.jboss.aspects.tx.TxInterceptor$Supports.invoke(TxInterceptor.java:148)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.tx.TxPropagationInterceptor.invoke(TxPropagationInterceptor.java:94)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at com.rim.bes.bas.BASExceptionStackInterceptor.invoke(BASExceptionStackInterceptor.java:85)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:70)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.security.RoleBasedAuthorizationInterceptor.invoke(RoleBasedAuthorizationInterceptor.java:166)

                at org.jboss.ejb3.security.RoleBasedAuthorizationInterceptor.invoke(RoleBasedAuthorizationInterceptor.java:108)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.security.AuthenticationInterceptor.invoke(AuthenticationInterceptor.java:77)

                at org.jboss.ejb3.security.Ejb3AuthenticationInterceptor.invoke(Ejb3AuthenticationInterceptor.java:139)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at com.rim.bes.bas.BASRemoteExceptionHandlerInterceptor.invoke(BASRemoteExceptionHandlerInterceptor.java:128)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.ENCPropagationInterceptor.invoke(ENCPropagationInterceptor.java:46)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.asynchronous.AsynchronousInterceptor.invoke(AsynchronousInterceptor.java:106)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.stateless.StatelessContainer.localInvoke(StatelessContainer.java:217)

                at org.jboss.ejb3.stateless.StatelessContainer.localInvoke(StatelessContainer.java:187)

                at org.jboss.ejb3.stateless.StatelessLocalProxy.invoke(StatelessLocalProxy.java:81)

                at $Proxy597.executeTimerHandlerAsyncMethodLocal(Unknown Source)

                at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

                at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at com.rim.bes.bas.asynchronousmanager.AsynchronousManagerBean._executeRequestMethod(AsynchronousManagerBean.java:510)

                at com.rim.bes.bas.asynchronousmanager.AsynchronousManagerBean.processRequestMessageInNewTransactionLocal(AsynchronousManagerBean.java:278)

                at sun.reflect.GeneratedMethodAccessor729.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:112)

                at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:166)

                at com.rim.bes.bas.BASCustomAnnotationHandlerInterceptor.execute(BASCustomAnnotationHandlerInterceptor.java:106)

                at sun.reflect.GeneratedMethodAccessor192.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:118)

                at org.jboss.ejb3.interceptor.EJB3InterceptorsInterceptor.invoke(EJB3InterceptorsInterceptor.java:63)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.entity.TransactionScopedEntityManagerInterceptor.invoke(TransactionScopedEntityManagerInterceptor.java:54)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.AllowedOperationsInterceptor.invoke(AllowedOperationsInterceptor.java:47)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at com.rim.bes.bas.BASApplicationDeadlockInterceptor.invoke(BASApplicationDeadlockInterceptor.java:99)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.tx.TxPolicy.invokeInOurTx(TxPolicy.java:79)

                at org.jboss.aspects.tx.TxInterceptor$RequiresNew.invoke(TxInterceptor.java:253)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.tx.TxPropagationInterceptor.invoke(TxPropagationInterceptor.java:94)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at com.rim.bes.bas.BASExceptionStackInterceptor.invoke(BASExceptionStackInterceptor.java:85)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:70)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.remoting.ReplicantsManagerInterceptor.invoke(ReplicantsManagerInterceptor.java:51)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.security.RoleBasedAuthorizationInterceptor.invoke(RoleBasedAuthorizationInterceptor.java:166)

                at org.jboss.ejb3.security.RoleBasedAuthorizationInterceptor.invoke(RoleBasedAuthorizationInterceptor.java:108)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.security.AuthenticationInterceptor.invoke(AuthenticationInterceptor.java:77)

                at org.jboss.ejb3.security.Ejb3AuthenticationInterceptor.invoke(Ejb3AuthenticationInterceptor.java:139)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at com.rim.bes.bas.BASRemoteExceptionHandlerInterceptor.invoke(BASRemoteExceptionHandlerInterceptor.java:128)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.ENCPropagationInterceptor.invoke(ENCPropagationInterceptor.java:46)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.asynchronous.AsynchronousInterceptor.invoke(AsynchronousInterceptor.java:106)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.stateless.StatelessContainer.localInvoke(StatelessContainer.java:217)

                at org.jboss.ejb3.stateless.StatelessContainer.localInvoke(StatelessContainer.java:187)

                at org.jboss.ejb3.stateless.StatelessLocalProxy.invoke(StatelessLocalProxy.java:81)

                at $Proxy502.processRequestMessageInNewTransactionLocal(Unknown Source)

                at com.rim.bes.bas.asynchronousmanager.message.AsynchronousManagerRequestProcessingBaseMDB.processRequestMessage(AsynchronousManagerRequestProcessingBaseMDB.java:348)

                at com.rim.bes.bas.asynchronousmanager.message.AsynchronousManagerRequestProcessingBaseMDB.onMessage(AsynchronousManagerRequestProcessingBaseMDB.java:233)

                at sun.reflect.GeneratedMethodAccessor724.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:112)

                at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:166)

                at com.rim.bes.bas.BASCustomAnnotationHandlerInterceptor.execute(BASCustomAnnotationHandlerInterceptor.java:106)

                at sun.reflect.GeneratedMethodAccessor192.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:118)

                at org.jboss.ejb3.interceptor.EJB3InterceptorsInterceptor.invoke(EJB3InterceptorsInterceptor.java:63)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.entity.TransactionScopedEntityManagerInterceptor.invoke(TransactionScopedEntityManagerInterceptor.java:54)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.AllowedOperationsInterceptor.invoke(AllowedOperationsInterceptor.java:47)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.tx.TxPolicy.invokeInCallerTx(TxPolicy.java:126)

                at org.jboss.aspects.tx.TxInterceptor$Required.invoke(TxInterceptor.java:195)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:70)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.tx.NullInterceptor.invoke(NullInterceptor.java:42)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:279)

                at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:266)

                at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:314)

                at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:170)

                at $Proxy599.onMessage(Unknown Source)

                at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:179)

                at org.jboss.jms.client.container.ClientConsumer.callOnMessageStatic(ClientConsumer.java:160)

                at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:831)

                at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect14.invoke(SessionAspect14.java)

                at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)

                at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:172)

                at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)

                at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)

                at org.jboss.jms.client.delegate.ClientSessionDelegate.run(ClientSessionDelegate.java)

                at org.jboss.jms.client.JBossSession.run(JBossSession.java:199)

                at org.jboss.resource.adapter.jms.inflow.JmsServerSession.run(JmsServerSession.java:239)

                at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:213)

                at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:275)

                at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:756)

                at java.lang.Thread.run(Unknown Source)

Caused by: com.rim.bes.bas.windowsaccess.windowshttpaccessor.WindowsHTTPJNIException: WinHTTP exception occurred in function: JWinHTTP::getResultFile, Url: https://www.blackberry.com/Desktop/Download/XML/Device.xml, Error: 0 - The operation completed successfully.  [ GetResultFile no HTTP response results after all proxies]

                at com.rim.bes.bas.windowsaccess.windowshttpaccessor.WindowsHTTPAccessorImpl.retrieveFileFromWebJNI(Native Method)

                at com.rim.bes.bas.windowsaccess.windowshttpaccessor.WindowsHTTPAccessorImpl.retrieveFileFromWeb(WindowsHTTPAccessorImpl.java:112)

                at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

                at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:112)

                at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:166)

                at org.jboss.ejb3.interceptor.EJB3InterceptorsInterceptor.invoke(EJB3InterceptorsInterceptor.java:63)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.connectionmanager.CachedConnectionInterceptor.invoke(CachedConnectionInterceptor.java:57)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.entity.TransactionScopedEntityManagerInterceptor.invoke(TransactionScopedEntityManagerInterceptor.java:54)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.AllowedOperationsInterceptor.invoke(AllowedOperationsInterceptor.java:47)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.timer.TimerTxRegistrationInterceptor.invoke(TimerTxRegistrationInterceptor.java:38)

                at org.jboss.ejb3.timer.TimerTxRegistrationInterceptor.invoke(TimerTxRegistrationInterceptor.java:29)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.tx.TxPolicy.invokeInOurTx(TxPolicy.java:79)

                at org.jboss.aspects.tx.TxInterceptor$Required.invoke(TxInterceptor.java:191)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.tx.TxPropagationInterceptor.invoke(TxPropagationInterceptor.java:94)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:70)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.security.RunAsSecurityInterceptor.invoke(RunAsSecurityInterceptor.java:59)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.security.RoleBasedAuthorizationInterceptor.invoke(RoleBasedAuthorizationInterceptor.java:166)

                at org.jboss.ejb3.security.RoleBasedAuthorizationInterceptor.invoke(RoleBasedAuthorizationInterceptor.java:108)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.security.AuthenticationInterceptor.invoke(AuthenticationInterceptor.java:77)

                at org.jboss.ejb3.security.Ejb3AuthenticationInterceptor.invoke(Ejb3AuthenticationInterceptor.java:139)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.ENCPropagationInterceptor.invoke(ENCPropagationInterceptor.java:46)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.interceptor.EJB3TCCLInterceptor.invoke(EJB3TCCLInterceptor.java:86)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.asynchronous.AsynchronousInterceptor.invoke(AsynchronousInterceptor.java:106)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.stateless.StatelessContainer.dynamicInvoke(StatelessContainer.java:281)

                at org.jboss.aop.Dispatcher.invoke(Dispatcher.java:106)

                at org.jboss.aspects.remoting.AOPRemotingInvocationHandler.invoke(AOPRemotingInvocationHandler.java:82)

                at org.jboss.remoting.ServerInvoker.invoke(ServerInvoker.java:862)

                at org.jboss.remoting.transport.socket.ServerThread.processInvocation(ServerThread.java:634)

                at org.jboss.remoting.transport.socket.ServerThread.dorun(ServerThread.java:409)

                at org.jboss.remoting.transport.socket.ServerThread.run(ServerThread.java:176)

                at org.jboss.remoting.MicroRemoteClientInvoker.invoke(MicroRemoteClientInvoker.java:182)

                at org.jboss.remoting.Client.invoke(Client.java:1935)

                at org.jboss.remoting.Client.invoke(Client.java:788)

                at org.jboss.aspects.remoting.InvokeRemoteInterceptor.invoke(InvokeRemoteInterceptor.java:62)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.tx.ClientTxPropagationInterceptor.invoke(ClientTxPropagationInterceptor.java:61)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.security.SecurityClientInterceptor.invoke(SecurityClientInterceptor.java:53)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.remoting.IsLocalInterceptor.invoke(IsLocalInterceptor.java:74)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.stateless.StatelessRemoteProxy.invoke(StatelessRemoteProxy.java:107)

                at $Proxy675.retrieveFileFromWeb(Unknown Source)

                at com.rim.bes.bas.windowsaccess.windowshttpaccessor.WindowsHTTPAccessorCallWrapper.run(WindowsHTTPAccessorCallWrapper.java:304)

                at com.rim.bes.bas.authenticationmanager.AuthenticationManagerUtilities.runAsCredentialsLocal(AuthenticationManagerUtilities.java:864)

                at com.rim.bes.bas.authenticationmanager.AuthenticationManagerUtilities.runAsAuthenticationCredentialsLocal(AuthenticationManagerUtilities.java:791)

                at com.rim.bes.bas.windowsaccess.windowshttpaccessor.WindowsHTTPAccessorUtilities.retrieveFileFromWeb(WindowsHTTPAccessorUtilities.java:116)

                at com.rim.bes.bas.softwareconfigurationmanager.SoftwareConfigurationManagerUtilities.retrieveExternalXMLFile(SoftwareConfigurationManagerUtilities.java:4438)

                at com.rim.bes.bas.softwareconfigurationmanager.SoftwareConfigurationManagerBean._retrieveExternalXML(SoftwareConfigurationManagerBean.java:9718)

                at com.rim.bes.bas.softwareconfigurationmanager.SoftwareConfigurationManagerBean.retrieveExternalXMLLocal(SoftwareConfigurationManagerBean.java:9649)

                at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

                at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:112)

                at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:166)

                at com.rim.bes.bas.BASCustomAnnotationHandlerInterceptor.execute(BASCustomAnnotationHandlerInterceptor.java:106)

                at sun.reflect.GeneratedMethodAccessor192.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:118)

                at org.jboss.ejb3.interceptor.EJB3InterceptorsInterceptor.invoke(EJB3InterceptorsInterceptor.java:63)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.entity.TransactionScopedEntityManagerInterceptor.invoke(TransactionScopedEntityManagerInterceptor.java:54)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.AllowedOperationsInterceptor.invoke(AllowedOperationsInterceptor.java:47)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at com.rim.bes.bas.BASApplicationDeadlockInterceptor.invoke(BASApplicationDeadlockInterceptor.java:99)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.tx.TxPolicy.invokeInCallerTx(TxPolicy.java:126)

                at org.jboss.aspects.tx.TxInterceptor$Required.invoke(TxInterceptor.java:195)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.tx.TxPropagationInterceptor.invoke(TxPropagationInterceptor.java:94)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at com.rim.bes.bas.BASExceptionStackInterceptor.invoke(BASExceptionStackInterceptor.java:85)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:70)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.remoting.ReplicantsManagerInterceptor.invoke(ReplicantsManagerInterceptor.java:51)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.security.RoleBasedAuthorizationInterceptor.invoke(RoleBasedAuthorizationInterceptor.java:166)

                at org.jboss.ejb3.security.RoleBasedAuthorizationInterceptor.invoke(RoleBasedAuthorizationInterceptor.java:108)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.security.AuthenticationInterceptor.invoke(AuthenticationInterceptor.java:77)

                at org.jboss.ejb3.security.Ejb3AuthenticationInterceptor.invoke(Ejb3AuthenticationInterceptor.java:139)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at com.rim.bes.bas.BASRemoteExceptionHandlerInterceptor.invoke(BASRemoteExceptionHandlerInterceptor.java:128)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.ENCPropagationInterceptor.invoke(ENCPropagationInterceptor.java:46)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.asynchronous.AsynchronousInterceptor.invoke(AsynchronousInterceptor.java:106)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.stateless.StatelessContainer.localInvoke(StatelessContainer.java:217)

                at org.jboss.ejb3.stateless.StatelessContainer.localInvoke(StatelessContainer.java:187)

                at org.jboss.ejb3.stateless.StatelessLocalProxy.invoke(StatelessLocalProxy.java:81)

                at $Proxy623.retrieveExternalXMLLocal(Unknown Source)

                at com.rim.bes.bas.softwareconfigurationmanager.ExternalXMLTimerHandler.execute(ExternalXMLTimerHandler.java:79)

                at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

                at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at com.rim.bes.bas.timermanager.TimerManagerBean._invokeExecuteMethod(TimerManagerBean.java:3148)

                at com.rim.bes.bas.timermanager.TimerManagerBean.executeTimerHandlerAsyncMethodLocal(TimerManagerBean.java:2847)

                at sun.reflect.GeneratedMethodAccessor732.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:112)

                at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:166)

                at com.rim.bes.bas.BASCustomAnnotationHandlerInterceptor.execute(BASCustomAnnotationHandlerInterceptor.java:106)

                at sun.reflect.GeneratedMethodAccessor192.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:118)

                at org.jboss.ejb3.interceptor.EJB3InterceptorsInterceptor.invoke(EJB3InterceptorsInterceptor.java:63)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.entity.TransactionScopedEntityManagerInterceptor.invoke(TransactionScopedEntityManagerInterceptor.java:54)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.AllowedOperationsInterceptor.invoke(AllowedOperationsInterceptor.java:47)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at com.rim.bes.bas.BASApplicationDeadlockInterceptor.invoke(BASApplicationDeadlockInterceptor.java:99)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.tx.TxPolicy.invokeInCallerTx(TxPolicy.java:126)

                at org.jboss.aspects.tx.TxInterceptor$Supports.invoke(TxInterceptor.java:148)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.tx.TxPropagationInterceptor.invoke(TxPropagationInterceptor.java:94)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at com.rim.bes.bas.BASExceptionStackInterceptor.invoke(BASExceptionStackInterceptor.java:85)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:70)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.security.RoleBasedAuthorizationInterceptor.invoke(RoleBasedAuthorizationInterceptor.java:166)

                at org.jboss.ejb3.security.RoleBasedAuthorizationInterceptor.invoke(RoleBasedAuthorizationInterceptor.java:108)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.security.AuthenticationInterceptor.invoke(AuthenticationInterceptor.java:77)

                at org.jboss.ejb3.security.Ejb3AuthenticationInterceptor.invoke(Ejb3AuthenticationInterceptor.java:139)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at com.rim.bes.bas.BASRemoteExceptionHandlerInterceptor.invoke(BASRemoteExceptionHandlerInterceptor.java:128)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.ENCPropagationInterceptor.invoke(ENCPropagationInterceptor.java:46)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.asynchronous.AsynchronousInterceptor.invoke(AsynchronousInterceptor.java:106)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.stateless.StatelessContainer.localInvoke(StatelessContainer.java:217)

                at org.jboss.ejb3.stateless.StatelessContainer.localInvoke(StatelessContainer.java:187)

                at org.jboss.ejb3.stateless.StatelessLocalProxy.invoke(StatelessLocalProxy.java:81)

                at $Proxy597.executeTimerHandlerAsyncMethodLocal(Unknown Source)

                at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

                at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at com.rim.bes.bas.asynchronousmanager.AsynchronousManagerBean._executeRequestMethod(AsynchronousManagerBean.java:510)

                at com.rim.bes.bas.asynchronousmanager.AsynchronousManagerBean.processRequestMessageInNewTransactionLocal(AsynchronousManagerBean.java:278)

                at sun.reflect.GeneratedMethodAccessor729.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:112)

                at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:166)

                at com.rim.bes.bas.BASCustomAnnotationHandlerInterceptor.execute(BASCustomAnnotationHandlerInterceptor.java:106)

                at sun.reflect.GeneratedMethodAccessor192.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:118)

                at org.jboss.ejb3.interceptor.EJB3InterceptorsInterceptor.invoke(EJB3InterceptorsInterceptor.java:63)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.entity.TransactionScopedEntityManagerInterceptor.invoke(TransactionScopedEntityManagerInterceptor.java:54)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.AllowedOperationsInterceptor.invoke(AllowedOperationsInterceptor.java:47)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at com.rim.bes.bas.BASApplicationDeadlockInterceptor.invoke(BASApplicationDeadlockInterceptor.java:99)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.tx.TxPolicy.invokeInOurTx(TxPolicy.java:79)

                at org.jboss.aspects.tx.TxInterceptor$RequiresNew.invoke(TxInterceptor.java:253)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.tx.TxPropagationInterceptor.invoke(TxPropagationInterceptor.java:94)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at com.rim.bes.bas.BASExceptionStackInterceptor.invoke(BASExceptionStackInterceptor.java:85)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:70)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.remoting.ReplicantsManagerInterceptor.invoke(ReplicantsManagerInterceptor.java:51)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.security.RoleBasedAuthorizationInterceptor.invoke(RoleBasedAuthorizationInterceptor.java:166)

                at org.jboss.ejb3.security.RoleBasedAuthorizationInterceptor.invoke(RoleBasedAuthorizationInterceptor.java:108)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.security.AuthenticationInterceptor.invoke(AuthenticationInterceptor.java:77)

                at org.jboss.ejb3.security.Ejb3AuthenticationInterceptor.invoke(Ejb3AuthenticationInterceptor.java:139)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at com.rim.bes.bas.BASRemoteExceptionHandlerInterceptor.invoke(BASRemoteExceptionHandlerInterceptor.java:128)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.ENCPropagationInterceptor.invoke(ENCPropagationInterceptor.java:46)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.asynchronous.AsynchronousInterceptor.invoke(AsynchronousInterceptor.java:106)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.stateless.StatelessContainer.localInvoke(StatelessContainer.java:217)

                at org.jboss.ejb3.stateless.StatelessContainer.localInvoke(StatelessContainer.java:187)

                at org.jboss.ejb3.stateless.StatelessLocalProxy.invoke(StatelessLocalProxy.java:81)

                at $Proxy502.processRequestMessageInNewTransactionLocal(Unknown Source)

                at com.rim.bes.bas.asynchronousmanager.message.AsynchronousManagerRequestProcessingBaseMDB.processRequestMessage(AsynchronousManagerRequestProcessingBaseMDB.java:348)

                at com.rim.bes.bas.asynchronousmanager.message.AsynchronousManagerRequestProcessingBaseMDB.onMessage(AsynchronousManagerRequestProcessingBaseMDB.java:233)

                at sun.reflect.GeneratedMethodAccessor724.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:112)

                at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:166)

                at com.rim.bes.bas.BASCustomAnnotationHandlerInterceptor.execute(BASCustomAnnotationHandlerInterceptor.java:106)

                at sun.reflect.GeneratedMethodAccessor192.invoke(Unknown Source)

                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

                at java.lang.reflect.Method.invoke(Unknown Source)

                at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:118)

                at org.jboss.ejb3.interceptor.EJB3InterceptorsInterceptor.invoke(EJB3InterceptorsInterceptor.java:63)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.entity.TransactionScopedEntityManagerInterceptor.invoke(TransactionScopedEntityManagerInterceptor.java:54)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.AllowedOperationsInterceptor.invoke(AllowedOperationsInterceptor.java:47)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.tx.TxPolicy.invokeInCallerTx(TxPolicy.java:126)

                at org.jboss.aspects.tx.TxInterceptor$Required.invoke(TxInterceptor.java:195)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:70)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.tx.NullInterceptor.invoke(NullInterceptor.java:42)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:279)

                at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:266)

                at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:314)

                at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:170)

                at $Proxy599.onMessage(Unknown Source)

                at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:179)

                at org.jboss.jms.client.container.ClientConsumer.callOnMessageStatic(ClientConsumer.java:160)

                at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:831)

                at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect14.invoke(SessionAspect14.java)

                at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)

                at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:172)

                at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)

                at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)

                at org.jboss.jms.client.delegate.ClientSessionDelegate.run(ClientSessionDelegate.java)

                at org.jboss.jms.client.JBossSession.run(JBossSession.java:199)

                at org.jboss.resource.adapter.jms.inflow.JmsServerSession.run(JmsServerSession.java:239)

                at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:213)

                at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:275)

                at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:756)

                at java.lang.Thread.run(Unknown Source)

                at org.jboss.aspects.remoting.InvokeRemoteInterceptor.invoke(InvokeRemoteInterceptor.java:74)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.tx.ClientTxPropagationInterceptor.invoke(ClientTxPropagationInterceptor.java:61)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.aspects.security.SecurityClientInterceptor.invoke(SecurityClientInterceptor.java:53)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.remoting.IsLocalInterceptor.invoke(IsLocalInterceptor.java:74)

                at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)

                at org.jboss.ejb3.stateless.StatelessRemoteProxy.invoke(StatelessRemoteProxy.java:107)

                at $Proxy675.retrieveFileFromWeb(Unknown Source)

                at com.rim.bes.bas.windowsaccess.windowshttpaccessor.WindowsHTTPAccessorCallWrapper.run(WindowsHTTPAccessorCallWrapper.java:304)

                at com.rim.bes.bas.authenticationmanager.AuthenticationManagerUtilities.runAsCredentialsLocal(AuthenticationManagerUtilities.java:864)

                at com.rim.bes.bas.authenticationmanager.AuthenticationManagerUtilities.runAsAuthenticationCredentialsLocal(AuthenticationManagerUtilities.java:791)

                at com.rim.bes.bas.windowsaccess.windowshttpaccessor.WindowsHTTPAccessorUtilities.retrieveFileFromWeb(WindowsHTTPAccessorUtilities.java:116)

                ... 195 more

 

*** end of original stack trace ***

Retired
gbeukeboom
Posts: 2,559
Registered: ‎10-16-2009
My Device: BlackBerry Z10

Re: Problems with custom java app running on device

I don't see any code to close the connection and output streams, this could cause problems with connections in your application. The logic should be done in a finally() block so that regardless of any exceptions, the connections and streams are closed properly. 

 

Note that the BBAS logs are just for the BAS console and should be irrelevant to troubleshooting MDS-CS connection issues.

 

Regards,

Garett
@garettBeuk
--
Goodbye everybody!
New Contributor
marevens
Posts: 5
Registered: ‎03-22-2011
My Device: none

Re: Problems with custom java app running on device

Thx, for your response. I have added a finally block now.

 

Unfortunately, the problem still exists. Yesterday I ran 20 tests successfully, and waited for 2 hours, and then I had no connection. And the logs are noe giving me anything. I wonder if the error is on my java app, or the bes environment. We are running bes express, but still I would excpect some error details in the log if the connection is broken.

 

Here is my function performing request to bes/mds:

 

private boolean sendStatusMessage(String phoneNumber, String status) {
		StreamConnection conn = null;
		try {
			String body = "<soapenv:Envelope xmlns:soapenv=\"http://schemas.xmlsoap.org/soap/envelope/\" xmlns:i3w=\"http://I3WebAction\">"
					+ "<soapenv:Header/>"
					+ "<soapenv:Body>"
					+ "<i3w:I3SetMobileStatus><i3w:p_Status>"
					+ status
					+ "</i3w:p_Status><i3w:p_PhoneNumber>"
					+ phoneNumber
					+ "</i3w:p_PhoneNumber>"
					+ "</i3w:I3SetMobileStatus></soapenv:Body></soapenv:Envelope>";

			String URL = "socket://" + soapServer + ":" + port
					+ ";deviceside=false";
			conn = (StreamConnection) Connector.open(URL);

			OutputStream output = conn.openOutputStream();
			OutputStreamWriter writer = new OutputStreamWriter(output);
			writer.write("POST /SOAPListener/I3SOAPISAPIU.dll HTTP/1.1\r\n");
			writer.write("Accept-Encoding: gzip,deflate\r\n");
			writer.write("Content-Type: text/xml;charset=UTF-8\r\n");
			writer.write("SOAPAction: \"http://I3WebAction/I3SetMobileStatus\"\r\n");
			writer.write("User-Agent: Jakarta Commons-HttpClient/3.1\r\n");
			writer.write("Host: lvkdb01\r\n");
			writer.write("Content-Length: " + body.length() + "\r\n");
			writer.write("\r\n");
			writer.write(body);

			writer.flush();
		} catch (Exception e) {
			Dialog.alert(e.getMessage());
			return false;
		} finally {
			try {
				// Close stream regardless of exceptions and return-points
				conn.close();
			} catch (IOException e) {
				// If closing the stream causes exception, the stream is most
				// likely not open or available. We display an error message,
				// and continues the program.
				Dialog.alert(e.getMessage());
				return false;
			}
		}
		return true;
	}

 

When my request is prosessed for the first time, I see this in mdat-log. First time only.

 

<2011-08-29 12:21:24.212 CEST>:[1370]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Receiving, VERSION = 1, COMMAND = RECEIVE, TAG = 8855, SIZE = 730>
<2011-08-29 12:21:24.227 CEST>:[1371]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = RECEIVE, TAG = 8855, SIZE = 10>
<2011-08-29 12:21:24.243 CEST>:[1372]:<MDS-CS_LVKBB_MDS-CS_1>:<WARNG>:<LAYER = SCM, Cannot load GME compression library>
<2011-08-29 12:21:24.243 CEST>:[1373]:<MDS-CS_LVKBB_MDS-CS_1>:<WARNG>:<LAYER = SCM, OS: Windows 2003>
<2011-08-29 12:21:24.243 CEST>:[1374]:<MDS-CS_LVKBB_MDS-CS_1>:<WARNG>:<LAYER = SCM, OS Arch: x86>
<2011-08-29 12:21:24.243 CEST>:[1375]:<MDS-CS_LVKBB_MDS-CS_1>:<WARNG>:<LAYER = SCM, OS Version: 5.2>

 

 

My mdat-log shows something like this when requests from java app is coming through:

<2011-08-29 12:22:34.913 CEST>:[1445]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Receiving, VERSION = 1, COMMAND = RECEIVE, TAG = 8858, SIZE = 714>
<2011-08-29 12:22:34.913 CEST>:[1446]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = RECEIVE, TAG = 8858, SIZE = 10>
<2011-08-29 12:22:34.913 CEST>:[1447]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = Receiving, TAG = -106703614, DEVICEPIN = 276502fe, USERID = u5, VERSION = 16, CONNECTIONID = 468986905, SEQUENCE = 0, TYPE = CONNECTION-REQUEST, CONNECTIONHANDLER = defaulthandlernio, PROTOCOL = tcpchannel, PARAMETERS = [lvkdb01:80], SIZE = 553>
<2011-08-29 12:22:34.913 CEST>:[1448]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = CreatedReceivingQueue, USERID:CONNECTIONID = u5:468986905, ReceivingQueueSize = 1>
<2011-08-29 12:22:34.913 CEST>:[1449]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = StartExecuting, TAG = -106703614, DEVICEPIN = 276502fe, USERID = u5, VERSION = 16, CONNECTIONID = 468986905, SEQUENCE = 0, TYPE = CONNECTION-REQUEST, CONNECTIONHANDLER = defaulthandlernio, PROTOCOL = tcpchannel, PARAMETERS = [lvkdb01:80], SIZE = 553>
<2011-08-29 12:22:34.913 CEST>:[1450]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = EndExecuting, TAG = -106703614, DEVICEPIN = 276502fe, USERID = u5, VERSION = 16, CONNECTIONID = 468986905, SEQUENCE = 0, TYPE = CONNECTION-REQUEST, CONNECTIONHANDLER = defaulthandlernio, PROTOCOL = tcpchannel, PARAMETERS = [lvkdb01:80], SIZE = 553>
<2011-08-29 12:22:34.913 CEST>:[1451]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, SocketChannel callback starting:u5>
<2011-08-29 12:22:34.913 CEST>:[1452]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Socket channel established; address:lvkdb01:80; time:0 ms>
<2011-08-29 12:22:34.929 CEST>:[1453]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, User has been logged off:lvkdb01:80:u5:468986905;>
<2011-08-29 12:22:34.929 CEST>:[1454]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Device connections: AVG latency (msecs)16>
<2011-08-29 12:22:34.929 CEST>:[1455]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = IPPP, DEVICEPIN = 276502fe, DOMAINNAME = lvkdb01, CONNECTION_TYPE = DEVICE_CONN, ConnectionId = 468986905, DURATION(ms) = 16, MFH_KBytes = 0,54, MTH_KBytes = 0, MFH_PACKET_COUNT = 1, MTH_PACKET_COUNT = 0>
<2011-08-29 12:22:34.929 CEST>:[1456]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, defaulthandlernio: ipppIoThreadPool-9 stopped>
<2011-08-29 12:22:34.929 CEST>:[1457]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, defaulthandlernio: ipppIoThreadPool-9 cleaned up>
<2011-08-29 12:22:34.929 CEST>:[1458]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = CreatedSendingQueue, DEVICEPIN = 276502fe, USERID = u5>
<2011-08-29 12:22:34.929 CEST>:[1459]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = SEND, TAG = 618528772, SIZE = 682>
<2011-08-29 12:22:34.929 CEST>:[1460]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = Sending, TAG = 618528772, DEVICEPIN = 276502fe, USERID = u5, VERSION = 16, CONNECTIONID = 468986905, SEQUENCE = 0, TYPE = DISCONNECT-ORDER, SIZE = 540>
<2011-08-29 12:22:34.929 CEST>:[1461]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Channel is closed>
<2011-08-29 12:22:34.929 CEST>:[1462]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = RemovedReceivingQueue, USERID:CONNECTIONID = u5:468986905, ReceivingQueueSize = 0>
<2011-08-29 12:22:34.929 CEST>:[1463]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Channel is closed>

 

 

I can't figure out what is causing the breaked connection.

 

Any comments appreciated :-)

Retired
gbeukeboom
Posts: 2,559
Registered: ‎10-16-2009
My Device: BlackBerry Z10

Re: Problems with custom java app running on device

Make sure to also close your OutputStream, and close in the opposite order you open. The following article explains this:

http://supportforums.blackberry.com/t5/Java-Development/Close-connections/ta-p/444878

 

The issue could be a result of this.

Garett
@garettBeuk
--
Goodbye everybody!
New Contributor
marevens
Posts: 5
Registered: ‎03-22-2011
My Device: none

Re: Problems with custom java app running on device

Thank you for your responses. So good to have some fresh eyes on my code. Really appreciate it. 

 

I have added code to close both the output stream and the connection. I'v added it in a try/catch. I am still experiencing that the solution is not stabile. I am not seeing any error messages on the phone, which I would expect when using:

Dialog.alert(e.getMessage());

 

Yesterday I ran 150 messages through the system in 20 minutes, and everything worked fine. After a break of 2-3 hours, the solution also did good. But then again after some more messages, it stops working.

 

Today I did one test at 09:46, which went through ok. But my next test at 10:02 failed. I am attaching the updated java code together with mdat log and route log.

 

Java code:

private boolean sendStatusMessage(String phoneNumber, String status) {
		StreamConnection conn = null;
		OutputStream output = null; //mari added
		
		try {
			String body = "<soapenv:Envelope xmlns:soapenv=\"http://schemas.xmlsoap.org/soap/envelope/\" xmlns:i3w=\"http://I3WebAction\">"
					+ "<soapenv:Header/>"
					+ "<soapenv:Body>"
					+ "<i3w:I3SetMobileStatus><i3w:p_Status>"
					+ status
					+ "</i3w:p_Status><i3w:p_PhoneNumber>"
					+ phoneNumber
					+ "</i3w:p_PhoneNumber>"
					+ "</i3w:I3SetMobileStatus></soapenv:Body></soapenv:Envelope>";

			String URL = "socket://" + soapServer + ":" + port
					+ ";deviceside=false";
			conn = (StreamConnection) Connector.open(URL);

			//OutputStream output = conn.openOutputStream();
			output = conn.openOutputStream();			
			
			OutputStreamWriter writer = new OutputStreamWriter(output);
			writer.write("POST /SOAPListener/I3SOAPISAPIU.dll HTTP/1.1\r\n");
			writer.write("Accept-Encoding: gzip,deflate\r\n");
			writer.write("Content-Type: text/xml;charset=UTF-8\r\n");
			writer.write("SOAPAction: \"http://I3WebAction/I3SetMobileStatus\"\r\n");
			writer.write("User-Agent: Jakarta Commons-HttpClient/3.1\r\n");
			writer.write("Host: lvkdb01\r\n");
			writer.write("Content-Length: " + body.length() + "\r\n");
			writer.write("\r\n");
			writer.write(body);

			writer.flush();
		} catch (Exception e) {
			Dialog.alert(e.getMessage());
			return false;
		} finally {
			try {
				// Close stream regardless of exceptions and return-points
				output.close();
			} catch (IOException e) {
				// If closing the stream causes exception, the stream is most
				// likely not open or available. We display an error message,
				// and continues the program.
				Dialog.alert(e.getMessage());
				return false;
			}
			try {
				// Close stream regardless of exceptions and return-points
				conn.close();
			} catch (IOException e) {
				// If closing the stream causes exception, the stream is most
				// likely not open or available. We display an error message,
				// and continues the program.
				Dialog.alert(e.getMessage());
				return false;
			}
		}
		return true;
	}

 

Route-log:

 

[30000] (09/08 09:23:06.413):{0xD24} [SERVICE_MANAGER] Connection established. Session=0x00AF10E8
[30000] (09/08 09:23:06.413):{0xD24} [SERVICE_RELAY_SESSION:UNKNOWN:0x00AF10E8] Accept service session. IP=127.0.0.1, ServiceSession=0x009E67D8
[30000] (09/08 09:23:06.593):{0xD14} [DIAG] EVENT=Register_thread, THREADID=0xD14, THREADNAME="SRPReceiverHandler"
[30000] (09/08 09:23:06.593):{0xD2C} [SERVICE_RELAY_SESSION:S13100005:0x00AF10E8] Service claim UID. UID: S13100005 Requested SRP Version:3, IP=127.0.0.1
[30000] (09/08 09:23:06.773):{0xDA0} [DIAG] EVENT=Register_thread, THREADID=0xDA0, THREADNAME="Unnamed"
[30000] (09/08 09:23:06.773):{0xDA0} [RELAY_SRP:UNKNOWN:0x00B09930] Connecting to relay at address "no.srp.blackberry.com"
[30000] (09/08 09:23:06.908):{0xDA0} [RELAY_SRP:UNKNOWN:0x00B09930] Connect: Found 2 addresses for host "no.srp.blackberry.com"
[30000] (09/08 09:23:06.908):{0xDA0} [RELAY_SRP:UNKNOWN:0x00B09930] Connect: Host "no.srp.blackberry.com" @ [193.109.81.33]
[30000] (09/08 09:23:06.908):{0xDA0} [RELAY_SRP:UNKNOWN:0x00B09930] Connect: Host "no.srp.blackberry.com" @ [93.186.25.33]
[30000] (09/08 09:23:06.908):{0xDA0} [RELAY_SRP:UNKNOWN:0x00B09930] Connect: Trying "no.srp.blackberry.com" @ [193.109.81.33]
[30000] (09/08 09:23:06.953):{0xDA0} [SERVICE_RELAY_SESSION:S13100005:0x00AF10E8] Connection to relay established.
[30000] (09/08 09:23:06.953):{0xDA0} [DIAG] EVENT=Unregister_thread, THREADID=0xDA0, THREADNAME="Unnamed"
[30000] (09/08 09:23:07.134):{0xD2C} [SERVICE_RELAY_SESSION:S13100005:0x00AF10E8] Service relay connection authenticated. SRPVersion: 3
[30000] (09/08 09:23:07.134):{0xD2C} [SERVICE_MANAGER] Session authenticated. UID=S13100005, Session=0x00AF10E8
[30000] (09/08 09:23:07.134):{0xD2C} [DEVICE_MANAGER] SRP StartListening. Port 4101.
[30000] (09/08 09:23:07.179):{0xDA8} [DIAG] EVENT=Register_thread, THREADID=0xDA8, THREADNAME="SRPListenerImp"
[30000] (09/08 09:55:04.990):{0xD2C} [RELAY_SRP:S13100005:0x00B09930] Receive: no data, ERROR=0
[30000] (09/08 09:55:04.990):{0xD2C} [SERVICE_RELAY_SESSION:S13100005:0x00AF10E8] Disconnect relay session. IP=127.0.0.1
[30000] (09/08 09:55:04.990):{0xD2C} [RELAY_SRP:S13100005:0x00B09930] disconnect
[30000] (09/08 09:55:04.990):{0xD2C} [SERVICE_RELAY_SESSION:S13100005:0x00AF10E8] Relay connection down. Continuing in bypass mode until relay connection can be re-established.
[30000] (09/08 09:55:04.990):{0xD2C} [SERVICE_SRP:S13100005:0x009E67D8] Receive: ERROR=10054, IP=127.0.0.1
[30000] (09/08 09:55:04.990):{0xD2C} [SERVICE_RELAY_SESSION:S13100005:0x00AF10E8] Disconnect session. IP=127.0.0.1
[30000] (09/08 09:55:04.990):{0xD2C} [SERVICE_SRP:S13100005:0x009E67D8] disconnect
[30000] (09/08 09:55:05.006):{0x18B0} [DIAG] EVENT=Register_thread, THREADID=0x18B0, THREADNAME="Unnamed"
[30000] (09/08 09:55:05.990):{0xD14} [DIAG] EVENT=Unregister_thread, THREADID=0xD14, THREADNAME="SRPReceiverHandler"
[30000] (09/08 09:55:05.990):{0xD2C} [RELAY_SRP:S13100005:0x00B09930] disconnect
[30000] (09/08 09:55:05.990):{0x18B0} [DIAG] EVENT=Unregister_thread, THREADID=0x18B0, THREADNAME="Unnamed"
[30000] (09/08 09:55:05.990):{0xD2C} [SERVICE_MANAGER] Delete session. UID=S13100005, Session=0x00AF10E8
[30000] (09/08 09:55:05.990):{0xD2C} [DEVICE_MANAGER] SRP StopListening. Port 4101.
[30000] (09/08 09:55:05.990):{0xDA8} [DIAG] EVENT=Unregister_thread, THREADID=0xDA8, THREADNAME="SRPListenerImp"
[30000] (09/08 09:55:10.068):{0xD24} [SERVICE_MANAGER] Connection established. Session=0x00AF68C8
[30000] (09/08 09:55:10.068):{0xD24} [SERVICE_RELAY_SESSION:UNKNOWN:0x00AF68C8] Accept service session. IP=127.0.0.1, ServiceSession=0x00AF38F8
[30000] (09/08 09:55:10.068):{0x1840} [DIAG] EVENT=Register_thread, THREADID=0x1840, THREADNAME="SRPReceiverHandler"
[30000] (09/08 09:55:10.068):{0xD2C} [SERVICE_RELAY_SESSION:S13100005:0x00AF68C8] Service claim UID. UID: S13100005 Requested SRP Version:3, IP=127.0.0.1
[30000] (09/08 09:55:10.068):{0x1828} [DIAG] EVENT=Register_thread, THREADID=0x1828, THREADNAME="Unnamed"
[30000] (09/08 09:55:10.068):{0x1828} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connecting to relay at address "no.srp.blackberry.com"
[30000] (09/08 09:55:10.193):{0x1828} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Found 2 addresses for host "no.srp.blackberry.com"
[30000] (09/08 09:55:10.193):{0x1828} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Host "no.srp.blackberry.com" @ [193.109.81.33]
[30000] (09/08 09:55:10.193):{0x1828} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Host "no.srp.blackberry.com" @ [93.186.25.33]
[30000] (09/08 09:55:10.193):{0x1828} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Trying "no.srp.blackberry.com" @ [193.109.81.33]
[30000] (09/08 09:55:10.255):{0x1828} [SERVICE_RELAY_SESSION:S13100005:0x00AF68C8] Connection to relay established.
[30000] (09/08 09:55:10.255):{0x1828} [DIAG] EVENT=Unregister_thread, THREADID=0x1828, THREADNAME="Unnamed"
[30000] (09/08 09:55:10.490):{0xD2C} [SERVICE_SRP:S13100005:0x00AF38F8] Receive: no data, ERROR=0
[30000] (09/08 09:55:10.490):{0xD2C} [SERVICE_RELAY_SESSION:S13100005:0x00AF68C8] Disconnect session. IP=127.0.0.1
[30000] (09/08 09:55:10.490):{0xD2C} [SERVICE_SRP:S13100005:0x00AF38F8] disconnect
[30000] (09/08 09:55:10.490):{0xD2C} [RELAY_SRP:S13100005:0x00B2AB20] disconnect
[30000] (09/08 09:55:11.490):{0x1840} [DIAG] EVENT=Unregister_thread, THREADID=0x1840, THREADNAME="SRPReceiverHandler"
[30000] (09/08 09:55:11.490):{0xD2C} [SERVICE_MANAGER] Delete session. UID=S13100005, Session=0x00AF68C8
[30000] (09/08 09:55:11.490):{0xD2C} [RELAY_SRP:S13100005:0x00B2AB20] Receive: invalid socket
[30000] (09/08 09:55:11.490):{0xD2C} [SERVICE_SRP:S13100005:0x009E67D8] disconnect
[30000] (09/08 09:55:11.490):{0xD2C} [RELAY_SRP:S13100005:0x00B09930] disconnect
[30000] (09/08 09:55:20.739):{0xD2C} [SERVICE_SRP:S13100005:0x00AF38F8] disconnect
[30000] (09/08 09:55:20.739):{0xD2C} [RELAY_SRP:S13100005:0x00B2AB20] disconnect
[30000] (09/08 09:56:10.502):{0xD24} [SERVICE_MANAGER] Connection established. Session=0x00AF68C8
[30000] (09/08 09:56:10.502):{0xD24} [SERVICE_RELAY_SESSION:UNKNOWN:0x00AF68C8] Accept service session. IP=127.0.0.1, ServiceSession=0x00AF38F8
[30000] (09/08 09:56:10.517):{0x1268} [DIAG] EVENT=Register_thread, THREADID=0x1268, THREADNAME="SRPReceiverHandler"
[30000] (09/08 09:56:10.517):{0xD2C} [SERVICE_RELAY_SESSION:S13100005:0x00AF68C8] Service claim UID. UID: S13100005 Requested SRP Version:3, IP=127.0.0.1
[30000] (09/08 09:56:10.517):{0x1270} [DIAG] EVENT=Register_thread, THREADID=0x1270, THREADNAME="Unnamed"
[30000] (09/08 09:56:10.517):{0x1270} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connecting to relay at address "no.srp.blackberry.com"
[30000] (09/08 09:56:10.517):{0x1270} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Found 2 addresses for host "no.srp.blackberry.com"
[30000] (09/08 09:56:10.517):{0x1270} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Host "no.srp.blackberry.com" @ [193.109.81.33]
[30000] (09/08 09:56:10.517):{0x1270} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Host "no.srp.blackberry.com" @ [93.186.25.33]
[30000] (09/08 09:56:10.517):{0x1270} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Trying "no.srp.blackberry.com" @ [193.109.81.33]
[30000] (09/08 09:56:10.564):{0x1270} [SERVICE_RELAY_SESSION:S13100005:0x00AF68C8] Connection to relay established.
[30000] (09/08 09:56:10.564):{0x1270} [DIAG] EVENT=Unregister_thread, THREADID=0x1270, THREADNAME="Unnamed"
[30000] (09/08 09:56:11.173):{0xD2C} [SERVICE_SRP:S13100005:0x00AF38F8] Receive: no data, ERROR=0
[30000] (09/08 09:56:11.173):{0xD2C} [SERVICE_RELAY_SESSION:S13100005:0x00AF68C8] Disconnect session. IP=127.0.0.1
[30000] (09/08 09:56:11.173):{0xD2C} [SERVICE_SRP:S13100005:0x00AF38F8] disconnect
[30000] (09/08 09:56:11.173):{0xD2C} [RELAY_SRP:S13100005:0x00B2AB20] disconnect
[30000] (09/08 09:56:12.173):{0x1268} [DIAG] EVENT=Unregister_thread, THREADID=0x1268, THREADNAME="SRPReceiverHandler"
[30000] (09/08 09:56:12.173):{0xD2C} [SERVICE_MANAGER] Delete session. UID=S13100005, Session=0x00AF68C8
[30000] (09/08 09:56:12.173):{0xD2C} [RELAY_SRP:S13100005:0x00B2AB20] Receive: invalid socket
[30000] (09/08 09:56:20.735):{0xD2C} [SERVICE_SRP:S13100005:0x00AF38F8] disconnect
[30000] (09/08 09:56:20.735):{0xD2C} [RELAY_SRP:S13100005:0x00B2AB20] disconnect
[30000] (09/08 09:58:10.167):{0xD24} [SERVICE_MANAGER] Connection established. Session=0x00AF68C8
[30000] (09/08 09:58:10.167):{0xD24} [SERVICE_RELAY_SESSION:UNKNOWN:0x00AF68C8] Accept service session. IP=127.0.0.1, ServiceSession=0x00AF38F8
[30000] (09/08 09:58:10.167):{0x274} [DIAG] EVENT=Register_thread, THREADID=0x274, THREADNAME="SRPReceiverHandler"
[30000] (09/08 09:58:10.167):{0xD2C} [SERVICE_RELAY_SESSION:S13100005:0x00AF68C8] Service claim UID. UID: S13100005 Requested SRP Version:3, IP=127.0.0.1
[30000] (09/08 09:58:10.167):{0x334} [DIAG] EVENT=Register_thread, THREADID=0x334, THREADNAME="Unnamed"
[30000] (09/08 09:58:10.167):{0x334} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connecting to relay at address "no.srp.blackberry.com"
[30000] (09/08 09:58:10.167):{0x334} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Found 2 addresses for host "no.srp.blackberry.com"
[30000] (09/08 09:58:10.167):{0x334} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Host "no.srp.blackberry.com" @ [193.109.81.33]
[30000] (09/08 09:58:10.167):{0x334} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Host "no.srp.blackberry.com" @ [93.186.25.33]
[30000] (09/08 09:58:10.167):{0x334} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Trying "no.srp.blackberry.com" @ [193.109.81.33]
[30000] (09/08 09:58:10.558):{0x334} [SERVICE_RELAY_SESSION:S13100005:0x00AF68C8] Connection to relay established.
[30000] (09/08 09:58:10.558):{0x334} [DIAG] EVENT=Unregister_thread, THREADID=0x334, THREADNAME="Unnamed"
[30000] (09/08 09:58:12.307):{0xD2C} [SERVICE_SRP:S13100005:0x00AF38F8] Receive: no data, ERROR=0
[30000] (09/08 09:58:12.307):{0xD2C} [SERVICE_RELAY_SESSION:S13100005:0x00AF68C8] Disconnect session. IP=127.0.0.1
[30000] (09/08 09:58:12.307):{0xD2C} [SERVICE_SRP:S13100005:0x00AF38F8] disconnect
[30000] (09/08 09:58:12.307):{0xD2C} [RELAY_SRP:S13100005:0x00B2AB20] disconnect
[30000] (09/08 09:58:13.307):{0x274} [DIAG] EVENT=Unregister_thread, THREADID=0x274, THREADNAME="SRPReceiverHandler"
[30000] (09/08 09:58:13.307):{0xD2C} [SERVICE_MANAGER] Delete session. UID=S13100005, Session=0x00AF68C8
[30000] (09/08 09:58:13.307):{0xD2C} [RELAY_SRP:S13100005:0x00B2AB20] Receive: invalid socket
[30000] (09/08 09:58:20.729):{0xD2C} [SERVICE_SRP:S13100005:0x00AF38F8] disconnect
[30000] (09/08 09:58:20.729):{0xD2C} [RELAY_SRP:S13100005:0x00B2AB20] disconnect
[30000] (09/08 10:01:15.299):{0xD24} [SERVICE_MANAGER] Connection established. Session=0x00AF68C8
[30000] (09/08 10:01:15.299):{0xD24} [SERVICE_RELAY_SESSION:UNKNOWN:0x00AF68C8] Accept service session. IP=127.0.0.1, ServiceSession=0x00AF38F8
[30000] (09/08 10:01:15.299):{0x10B8} [DIAG] EVENT=Register_thread, THREADID=0x10B8, THREADNAME="SRPReceiverHandler"
[30000] (09/08 10:01:15.299):{0xD2C} [SERVICE_RELAY_SESSION:S13100005:0x00AF68C8] Service claim UID. UID: S13100005 Requested SRP Version:3, IP=127.0.0.1
[30000] (09/08 10:01:15.299):{0x1094} [DIAG] EVENT=Register_thread, THREADID=0x1094, THREADNAME="Unnamed"
[30000] (09/08 10:01:15.299):{0x1094} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connecting to relay at address "no.srp.blackberry.com"
[30000] (09/08 10:01:15.299):{0x1094} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Found 2 addresses for host "no.srp.blackberry.com"
[30000] (09/08 10:01:15.299):{0x1094} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Host "no.srp.blackberry.com" @ [193.109.81.33]
[30000] (09/08 10:01:15.299):{0x1094} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Host "no.srp.blackberry.com" @ [93.186.25.33]
[30000] (09/08 10:01:15.299):{0x1094} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Trying "no.srp.blackberry.com" @ [193.109.81.33]
[30000] (09/08 10:01:15.362):{0x1094} [SERVICE_RELAY_SESSION:S13100005:0x00AF68C8] Connection to relay established.
[30000] (09/08 10:01:15.362):{0x1094} [DIAG] EVENT=Unregister_thread, THREADID=0x1094, THREADNAME="Unnamed"
[30000] (09/08 10:01:15.596):{0xD2C} [SERVICE_SRP:S13100005:0x00AF38F8] Receive: no data, ERROR=0
[30000] (09/08 10:01:15.596):{0xD2C} [SERVICE_RELAY_SESSION:S13100005:0x00AF68C8] Disconnect session. IP=127.0.0.1
[30000] (09/08 10:01:15.596):{0xD2C} [SERVICE_SRP:S13100005:0x00AF38F8] disconnect
[30000] (09/08 10:01:15.596):{0xD2C} [RELAY_SRP:S13100005:0x00B2AB20] disconnect
[30000] (09/08 10:01:16.596):{0x10B8} [DIAG] EVENT=Unregister_thread, THREADID=0x10B8, THREADNAME="SRPReceiverHandler"
[30000] (09/08 10:01:16.596):{0xD2C} [SERVICE_MANAGER] Delete session. UID=S13100005, Session=0x00AF68C8
[30000] (09/08 10:01:16.596):{0xD2C} [RELAY_SRP:S13100005:0x00B2AB20] Receive: invalid socket
[30000] (09/08 10:01:20.721):{0xD2C} [SERVICE_SRP:S13100005:0x00AF38F8] disconnect
[30000] (09/08 10:01:20.721):{0xD2C} [RELAY_SRP:S13100005:0x00B2AB20] disconnect
[30000] (09/08 10:05:11.590):{0xD24} [SERVICE_MANAGER] Connection established. Session=0x00AF68C8
[30000] (09/08 10:05:11.590):{0xD24} [SERVICE_RELAY_SESSION:UNKNOWN:0x00AF68C8] Accept service session. IP=127.0.0.1, ServiceSession=0x00AF38F8
[30000] (09/08 10:05:11.590):{0xDB8} [DIAG] EVENT=Register_thread, THREADID=0xDB8, THREADNAME="SRPReceiverHandler"
[30000] (09/08 10:05:11.590):{0xD2C} [SERVICE_RELAY_SESSION:S13100005:0x00AF68C8] Service claim UID. UID: S13100005 Requested SRP Version:3, IP=127.0.0.1
[30000] (09/08 10:05:11.590):{0xD98} [DIAG] EVENT=Register_thread, THREADID=0xD98, THREADNAME="Unnamed"
[30000] (09/08 10:05:11.590):{0xD98} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connecting to relay at address "no.srp.blackberry.com"
[30000] (09/08 10:05:12.028):{0xD98} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Found 2 addresses for host "no.srp.blackberry.com"
[30000] (09/08 10:05:12.028):{0xD98} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Host "no.srp.blackberry.com" @ [193.109.81.33]
[30000] (09/08 10:05:12.028):{0xD98} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Host "no.srp.blackberry.com" @ [93.186.25.33]
[30000] (09/08 10:05:12.028):{0xD98} [RELAY_SRP:UNKNOWN:0x00B2AB20] Connect: Trying "no.srp.blackberry.com" @ [193.109.81.33]
[30000] (09/08 10:05:12.512):{0xD98} [SERVICE_RELAY_SESSION:S13100005:0x00AF68C8] Connection to relay established.
[30000] (09/08 10:05:12.512):{0xD98} [DIAG] EVENT=Unregister_thread, THREADID=0xD98, THREADNAME="Unnamed"
[30000] (09/08 10:05:13.731):{0xD2C} [SERVICE_SRP:S13100005:0x00AF38F8] Receive: no data, ERROR=0
[30000] (09/08 10:05:13.731):{0xD2C} [SERVICE_RELAY_SESSION:S13100005:0x00AF68C8] Disconnect session. IP=127.0.0.1
[30000] (09/08 10:05:13.731):{0xD2C} [SERVICE_SRP:S13100005:0x00AF38F8] disconnect
[30000] (09/08 10:05:13.731):{0xD2C} [RELAY_SRP:S13100005:0x00B2AB20] disconnect
[30000] (09/08 10:05:14.731):{0xDB8} [DIAG] EVENT=Unregister_thread, THREADID=0xDB8, THREADNAME="SRPReceiverHandler"
[30000] (09/08 10:05:14.731):{0xD2C} [SERVICE_MANAGER] Delete session. UID=S13100005, Session=0x00AF68C8
[30000] (09/08 10:05:14.731):{0xD2C} [RELAY_SRP:S13100005:0x00B2AB20] Receive: invalid socket
[30000] (09/08 10:05:20.715):{0xD2C} [SERVICE_SRP:S13100005:0x00AF38F8] disconnect
[30000] (09/08 10:05:20.715):{0xD2C} [RELAY_SRP:S13100005:0x00B2AB20] disconnect
[30000] (09/08 10:10:06.726):{0xD24} [SERVICE_MANAGER] Connection established. Session=0x00AF68C8
[30000] (09/08 10:10:06.726):{0xD24} [SERVICE_RELAY_SESSION:UNKNOWN:0x00AF68C8] Accept service session. IP=127.0.0.1, 

 

Log from mdat:

<2011-09-08 09:42:36.100 CEST>:[194]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Configuration data>
<2011-09-08 09:42:36.100 CEST>:[195]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Retrieve all server hosts values>
<2011-09-08 09:42:36.116 CEST>:[196]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh group>
<2011-09-08 09:42:36.116 CEST>:[197]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- PIN-Email mapping rows changed: 0>
<2011-09-08 09:42:36.116 CEST>:[198]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh user group membership>
<2011-09-08 09:42:36.116 CEST>:[199]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- HTTP Proxy Mappings>
<2011-09-08 09:42:36.116 CEST>:[200]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh media management>
<2011-09-08 09:42:36.116 CEST>:[201]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Access Control data>
<2011-09-08 09:42:36.116 CEST>:[202]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh IT policy>
<2011-09-08 09:42:36.116 CEST>:[203]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, existing RSA option = 0  , new RSA option = 0>
<2011-09-08 09:42:36.116 CEST>:[204]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task Finished>
<2011-09-08 09:43:33.544 CEST>:[205]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 09:43:33.544 CEST>:[206]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[60]>
<2011-09-08 09:44:33.468 CEST>:[207]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 09:44:33.468 CEST>:[208]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[60]>
<2011-09-08 09:45:33.415 CEST>:[209]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 09:45:33.415 CEST>:[210]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[60]>
<2011-09-08 09:46:20.862 CEST>:[211]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Receiving, VERSION = 1, COMMAND = RECEIVE, TAG = 10680, SIZE = 730>
<2011-09-08 09:46:20.893 CEST>:[212]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = RECEIVE, TAG = 10680, SIZE = 10>
<2011-09-08 09:46:20.924 CEST>:[213]:<MDS-CS_LVKBB_MDS-CS_1>:<WARNG>:<LAYER = SCM, Cannot load GME compression library>
<2011-09-08 09:46:20.924 CEST>:[214]:<MDS-CS_LVKBB_MDS-CS_1>:<WARNG>:<LAYER = SCM, OS: Windows 2003>
<2011-09-08 09:46:20.924 CEST>:[215]:<MDS-CS_LVKBB_MDS-CS_1>:<WARNG>:<LAYER = SCM, OS Arch: x86>
<2011-09-08 09:46:20.924 CEST>:[216]:<MDS-CS_LVKBB_MDS-CS_1>:<WARNG>:<LAYER = SCM, OS Version: 5.2>
<2011-09-08 09:46:21.034 CEST>:[217]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = Receiving, TAG = 123296179, DEVICEPIN = 276502fe, USERID = u5, VERSION = 16, CONNECTIONID = 934777438, SEQUENCE = 0, TYPE = CONNECTION-REQUEST, CONNECTIONHANDLER = defaulthandlernio, PROTOCOL = tcpchannel, PARAMETERS = [lvkdb01:80], SIZE = 555>
<2011-09-08 09:46:21.034 CEST>:[218]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = CreatedReceivingQueue, USERID:CONNECTIONID = u5:934777438, ReceivingQueueSize = 1>
<2011-09-08 09:46:21.034 CEST>:[219]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = StartExecuting, TAG = 123296179, DEVICEPIN = 276502fe, USERID = u5, VERSION = 16, CONNECTIONID = 934777438, SEQUENCE = 0, TYPE = CONNECTION-REQUEST, CONNECTIONHANDLER = defaulthandlernio, PROTOCOL = tcpchannel, PARAMETERS = [lvkdb01:80], SIZE = 555>
<2011-09-08 09:46:21.096 CEST>:[220]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = EndExecuting, TAG = 123296179, DEVICEPIN = 276502fe, USERID = u5, VERSION = 16, CONNECTIONID = 934777438, SEQUENCE = 0, TYPE = CONNECTION-REQUEST, CONNECTIONHANDLER = defaulthandlernio, PROTOCOL = tcpchannel, PARAMETERS = [lvkdb01:80], SIZE = 555>
<2011-09-08 09:46:21.252 CEST>:[221]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, SocketChannel callback starting:u5>
<2011-09-08 09:46:21.362 CEST>:[222]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Socket channel established; address:lvkdb01:80; time:110 ms>
<2011-09-08 09:46:21.830 CEST>:[223]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, User has been logged off:lvkdb01:80:u5:934777438;>
<2011-09-08 09:46:21.830 CEST>:[224]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Device connections: AVG latency (msecs)734>
<2011-09-08 09:46:21.830 CEST>:[225]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = IPPP, DEVICEPIN = 276502fe, DOMAINNAME = lvkdb01, CONNECTION_TYPE = DEVICE_CONN, ConnectionId = 934777438, DURATION(ms) = 734, MFH_KBytes = 0,542, MTH_KBytes = 0, MFH_PACKET_COUNT = 1, MTH_PACKET_COUNT = 0>
<2011-09-08 09:46:21.830 CEST>:[226]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, defaulthandlernio: ipppIoThreadPool-2 stopped>
<2011-09-08 09:46:21.830 CEST>:[227]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, defaulthandlernio: ipppIoThreadPool-2 cleaned up>
<2011-09-08 09:46:21.830 CEST>:[228]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = CreatedSendingQueue, DEVICEPIN = 276502fe, USERID = u5>
<2011-09-08 09:46:21.830 CEST>:[229]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = IPPP, Sending Queue Size per device =4097>
<2011-09-08 09:46:21.830 CEST>:[230]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = SEND, TAG = 812384257, SIZE = 682>
<2011-09-08 09:46:21.830 CEST>:[231]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = Sending, TAG = 812384257, DEVICEPIN = 276502fe, USERID = u5, VERSION = 16, CONNECTIONID = 934777438, SEQUENCE = 0, TYPE = DISCONNECT-ORDER, SIZE = 540>
<2011-09-08 09:46:21.830 CEST>:[232]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Channel is closed>
<2011-09-08 09:46:21.830 CEST>:[233]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = RemovedReceivingQueue, USERID:CONNECTIONID = u5:934777438, ReceivingQueueSize = 0>
<2011-09-08 09:46:21.830 CEST>:[234]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Channel is closed>
<2011-09-08 09:46:22.065 CEST>:[235]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Receiving, VERSION = 1, COMMAND = STATUS, TAG = 812384257, SIZE = 10, STATE = DELIVERED>
<2011-09-08 09:46:22.065 CEST>:[236]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = Notification, TAG = 812384257, STATE = DELIVERED>
<2011-09-08 09:46:22.439 CEST>:[237]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = RemovedSendingQueue, DEVICEPIN = 276502fe, USERID = u5>
<2011-09-08 09:46:30.623 CEST>:[238]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Receiving, VERSION = 1, COMMAND = RECEIVE, TAG = 10681, SIZE = 714>
<2011-09-08 09:46:30.623 CEST>:[239]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = RECEIVE, TAG = 10681, SIZE = 10>
<2011-09-08 09:46:30.623 CEST>:[240]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = Receiving, TAG = 123296180, DEVICEPIN = 276502fe, USERID = u5, VERSION = 16, CONNECTIONID = 934777439, SEQUENCE = 0, TYPE = CONNECTION-REQUEST, CONNECTIONHANDLER = defaulthandlernio, PROTOCOL = tcpchannel, PARAMETERS = [lvkdb01:80], SIZE = 553>
<2011-09-08 09:46:30.623 CEST>:[241]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = CreatedReceivingQueue, USERID:CONNECTIONID = u5:934777439, ReceivingQueueSize = 1>
<2011-09-08 09:46:30.623 CEST>:[242]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = StartExecuting, TAG = 123296180, DEVICEPIN = 276502fe, USERID = u5, VERSION = 16, CONNECTIONID = 934777439, SEQUENCE = 0, TYPE = CONNECTION-REQUEST, CONNECTIONHANDLER = defaulthandlernio, PROTOCOL = tcpchannel, PARAMETERS = [lvkdb01:80], SIZE = 553>
<2011-09-08 09:46:30.623 CEST>:[243]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = EndExecuting, TAG = 123296180, DEVICEPIN = 276502fe, USERID = u5, VERSION = 16, CONNECTIONID = 934777439, SEQUENCE = 0, TYPE = CONNECTION-REQUEST, CONNECTIONHANDLER = defaulthandlernio, PROTOCOL = tcpchannel, PARAMETERS = [lvkdb01:80], SIZE = 553>
<2011-09-08 09:46:30.623 CEST>:[244]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, SocketChannel callback starting:u5>
<2011-09-08 09:46:30.639 CEST>:[245]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Socket channel established; address:lvkdb01:80; time:16 ms>
<2011-09-08 09:46:30.639 CEST>:[246]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, User has been logged off:lvkdb01:80:u5:934777439;>
<2011-09-08 09:46:30.639 CEST>:[247]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Device connections: AVG latency (msecs)16>
<2011-09-08 09:46:30.639 CEST>:[248]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = IPPP, DEVICEPIN = 276502fe, DOMAINNAME = lvkdb01, CONNECTION_TYPE = DEVICE_CONN, ConnectionId = 934777439, DURATION(ms) = 16, MFH_KBytes = 0,54, MTH_KBytes = 0, MFH_PACKET_COUNT = 1, MTH_PACKET_COUNT = 0>
<2011-09-08 09:46:30.639 CEST>:[249]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, defaulthandlernio: ipppIoThreadPool-4 stopped>
<2011-09-08 09:46:30.639 CEST>:[250]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, defaulthandlernio: ipppIoThreadPool-4 cleaned up>
<2011-09-08 09:46:30.639 CEST>:[251]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = CreatedSendingQueue, DEVICEPIN = 276502fe, USERID = u5>
<2011-09-08 09:46:30.639 CEST>:[252]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = SEND, TAG = 812384258, SIZE = 682>
<2011-09-08 09:46:30.639 CEST>:[253]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = Sending, TAG = 812384258, DEVICEPIN = 276502fe, USERID = u5, VERSION = 16, CONNECTIONID = 934777439, SEQUENCE = 0, TYPE = DISCONNECT-ORDER, SIZE = 540>
<2011-09-08 09:46:30.639 CEST>:[254]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Channel is closed>
<2011-09-08 09:46:30.639 CEST>:[255]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = RemovedReceivingQueue, USERID:CONNECTIONID = u5:934777439, ReceivingQueueSize = 0>
<2011-09-08 09:46:30.639 CEST>:[256]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Channel is closed>
<2011-09-08 09:46:31.217 CEST>:[257]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Receiving, VERSION = 1, COMMAND = STATUS, TAG = 812384258, SIZE = 10, STATE = DELIVERED>
<2011-09-08 09:46:31.217 CEST>:[258]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = Notification, TAG = 812384258, STATE = DELIVERED>
<2011-09-08 09:46:33.387 CEST>:[259]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 09:46:33.387 CEST>:[260]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[55]>
<2011-09-08 09:46:34.434 CEST>:[261]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = IPPP, EVENT = RemovedSendingQueue, DEVICEPIN = 276502fe, USERID = u5>
<2011-09-08 09:47:33.360 CEST>:[262]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 09:47:33.360 CEST>:[263]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[55]>
<2011-09-08 09:47:35.859 CEST>:[264]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task started;>
<2011-09-08 09:47:35.859 CEST>:[265]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, MDSConfiguration updated for :[S13100005[LVKBB:3200]]. Connection Id :[121]>
<2011-09-08 09:47:35.859 CEST>:[266]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = SCM, Loaded network provider: 0/Windows/Native>
<2011-09-08 09:47:35.874 CEST>:[267]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = SCM, Default network provider : Windows/Native>
<2011-09-08 09:47:35.874 CEST>:[268]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = SCM, Initializing network connection cache  connPerPrincipal=2 connTimeToLive=300000>
<2011-09-08 09:47:35.874 CEST>:[269]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, Initializing srpServers :[S13100005[LVKBB:3200]]>
<2011-09-08 09:47:35.874 CEST>:[270]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Configuration data>
<2011-09-08 09:47:35.874 CEST>:[271]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Retrieve all server hosts values>
<2011-09-08 09:47:35.874 CEST>:[272]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh group>
<2011-09-08 09:47:35.874 CEST>:[273]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- PIN-Email mapping rows changed: 0>
<2011-09-08 09:47:35.874 CEST>:[274]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh user group membership>
<2011-09-08 09:47:35.874 CEST>:[275]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- HTTP Proxy Mappings>
<2011-09-08 09:47:35.874 CEST>:[276]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh media management>
<2011-09-08 09:47:35.874 CEST>:[277]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Access Control data>
<2011-09-08 09:47:35.874 CEST>:[278]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh IT policy>
<2011-09-08 09:47:35.874 CEST>:[279]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, existing RSA option = 0  , new RSA option = 0>
<2011-09-08 09:47:35.874 CEST>:[280]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task Finished>
<2011-09-08 09:48:33.348 CEST>:[281]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 09:48:33.348 CEST>:[282]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[55]>
<2011-09-08 09:49:33.320 CEST>:[283]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 09:49:33.320 CEST>:[284]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[55]>
<2011-09-08 09:50:33.301 CEST>:[285]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 09:50:33.301 CEST>:[286]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[55]>
<2011-09-08 09:51:33.291 CEST>:[287]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 09:51:33.291 CEST>:[288]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[55]>
<2011-09-08 09:52:33.281 CEST>:[289]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 09:52:33.281 CEST>:[290]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[55]>
<2011-09-08 09:52:35.781 CEST>:[291]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task started;>
<2011-09-08 09:52:35.796 CEST>:[292]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, MDSConfiguration updated for :[S13100005[LVKBB:3200]]. Connection Id :[121]>
<2011-09-08 09:52:35.796 CEST>:[293]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = SCM, Loaded network provider: 0/Windows/Native>
<2011-09-08 09:52:35.796 CEST>:[294]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = SCM, Default network provider : Windows/Native>
<2011-09-08 09:52:35.796 CEST>:[295]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = SCM, Initializing network connection cache  connPerPrincipal=2 connTimeToLive=300000>
<2011-09-08 09:52:35.796 CEST>:[296]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, Initializing srpServers :[S13100005[LVKBB:3200]]>
<2011-09-08 09:52:35.796 CEST>:[297]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Configuration data>
<2011-09-08 09:52:35.796 CEST>:[298]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Retrieve all server hosts values>
<2011-09-08 09:52:35.796 CEST>:[299]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh group>
<2011-09-08 09:52:35.796 CEST>:[300]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- PIN-Email mapping rows changed: 0>
<2011-09-08 09:52:35.796 CEST>:[301]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh user group membership>
<2011-09-08 09:52:35.796 CEST>:[302]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- HTTP Proxy Mappings>
<2011-09-08 09:52:35.796 CEST>:[303]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh media management>
<2011-09-08 09:52:35.796 CEST>:[304]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Access Control data>
<2011-09-08 09:52:35.796 CEST>:[305]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh IT policy>
<2011-09-08 09:52:35.796 CEST>:[306]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, existing RSA option = 0  , new RSA option = 0>
<2011-09-08 09:52:35.796 CEST>:[307]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task Finished>
<2011-09-08 09:53:33.271 CEST>:[308]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 09:53:33.271 CEST>:[309]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[55]>
<2011-09-08 09:54:33.261 CEST>:[310]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 09:54:33.261 CEST>:[311]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[55]>
<2011-09-08 09:55:33.254 CEST>:[312]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 09:55:33.254 CEST>:[313]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[55]>
<2011-09-08 09:56:33.250 CEST>:[314]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 09:56:33.250 CEST>:[315]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[55]>
<2011-09-08 09:57:33.247 CEST>:[316]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 09:57:33.247 CEST>:[317]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[55]>
<2011-09-08 09:57:35.762 CEST>:[318]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task started;>
<2011-09-08 09:57:35.778 CEST>:[319]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, MDSConfiguration updated for :[S13100005[LVKBB:3200]]. Connection Id :[121]>
<2011-09-08 09:57:35.778 CEST>:[320]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = SCM, Loaded network provider: 0/Windows/Native>
<2011-09-08 09:57:35.778 CEST>:[321]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = SCM, Default network provider : Windows/Native>
<2011-09-08 09:57:35.778 CEST>:[322]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = SCM, Initializing network connection cache  connPerPrincipal=2 connTimeToLive=300000>
<2011-09-08 09:57:35.778 CEST>:[323]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, Initializing srpServers :[S13100005[LVKBB:3200]]>
<2011-09-08 09:57:35.778 CEST>:[324]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Configuration data>
<2011-09-08 09:57:35.778 CEST>:[325]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Retrieve all server hosts values>
<2011-09-08 09:57:35.778 CEST>:[326]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh group>
<2011-09-08 09:57:35.793 CEST>:[327]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- PIN-Email mapping rows changed: 0>
<2011-09-08 09:57:35.793 CEST>:[328]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh user group membership>
<2011-09-08 09:57:35.793 CEST>:[329]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- HTTP Proxy Mappings>
<2011-09-08 09:57:35.793 CEST>:[330]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh media management>
<2011-09-08 09:57:35.793 CEST>:[331]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Access Control data>
<2011-09-08 09:57:35.793 CEST>:[332]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh IT policy>
<2011-09-08 09:57:35.793 CEST>:[333]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, existing RSA option = 0  , new RSA option = 0>
<2011-09-08 09:57:35.793 CEST>:[334]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task Finished>
<2011-09-08 09:58:33.260 CEST>:[335]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 09:58:33.260 CEST>:[336]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[55]>
<2011-09-08 09:59:33.257 CEST>:[337]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 09:59:33.257 CEST>:[338]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[55]>
<2011-09-08 10:00:33.254 CEST>:[339]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 10:00:33.254 CEST>:[340]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[55]>
<2011-09-08 10:01:33.252 CEST>:[341]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 10:01:33.252 CEST>:[342]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[55]>
<2011-09-08 10:02:33.250 CEST>:[343]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 10:02:33.250 CEST>:[344]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[55]>
<2011-09-08 10:02:35.781 CEST>:[345]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task started;>
<2011-09-08 10:02:35.781 CEST>:[346]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, MDSConfiguration updated for :[S13100005[LVKBB:3200]]. Connection Id :[121]>
<2011-09-08 10:02:35.781 CEST>:[347]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = SCM, Loaded network provider: 0/Windows/Native>
<2011-09-08 10:02:35.796 CEST>:[348]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = SCM, Default network provider : Windows/Native>
<2011-09-08 10:02:35.796 CEST>:[349]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = SCM, Initializing network connection cache  connPerPrincipal=2 connTimeToLive=300000>
<2011-09-08 10:02:35.796 CEST>:[350]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, Initializing srpServers :[S13100005[LVKBB:3200]]>
<2011-09-08 10:02:35.796 CEST>:[351]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Configuration data>
<2011-09-08 10:02:35.796 CEST>:[352]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Retrieve all server hosts values>
<2011-09-08 10:02:35.796 CEST>:[353]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh group>
<2011-09-08 10:02:35.796 CEST>:[354]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- PIN-Email mapping rows changed: 0>
<2011-09-08 10:02:35.796 CEST>:[355]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh user group membership>
<2011-09-08 10:02:35.796 CEST>:[356]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- HTTP Proxy Mappings>
<2011-09-08 10:02:35.796 CEST>:[357]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh media management>
<2011-09-08 10:02:35.796 CEST>:[358]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Access Control data>
<2011-09-08 10:02:35.796 CEST>:[359]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh IT policy>
<2011-09-08 10:02:35.796 CEST>:[360]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, existing RSA option = 0  , new RSA option = 0>
<2011-09-08 10:02:35.796 CEST>:[361]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task Finished>
<2011-09-08 10:03:33.248 CEST>:[362]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 10:03:33.248 CEST>:[363]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[56]>
<2011-09-08 10:04:33.247 CEST>:[364]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 10:04:33.247 CEST>:[365]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[56]>
<2011-09-08 10:05:33.246 CEST>:[366]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 10:05:33.246 CEST>:[367]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[56]>
<2011-09-08 10:06:33.245 CEST>:[368]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 10:06:33.245 CEST>:[369]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[56]>
<2011-09-08 10:07:33.244 CEST>:[370]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 10:07:33.244 CEST>:[371]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[56]>
<2011-09-08 10:07:35.791 CEST>:[372]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task started;>
<2011-09-08 10:07:35.806 CEST>:[373]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, MDSConfiguration updated for :[S13100005[LVKBB:3200]]. Connection Id :[121]>
<2011-09-08 10:07:35.806 CEST>:[374]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = SCM, Loaded network provider: 0/Windows/Native>
<2011-09-08 10:07:35.806 CEST>:[375]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = SCM, Default network provider : Windows/Native>
<2011-09-08 10:07:35.806 CEST>:[376]:<MDS-CS_LVKBB_MDS-CS_1>:<INFO >:<LAYER = SCM, Initializing network connection cache  connPerPrincipal=2 connTimeToLive=300000>
<2011-09-08 10:07:35.806 CEST>:[377]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, Initializing srpServers :[S13100005[LVKBB:3200]]>
<2011-09-08 10:07:35.806 CEST>:[378]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Configuration data>
<2011-09-08 10:07:35.806 CEST>:[379]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Retrieve all server hosts values>
<2011-09-08 10:07:35.822 CEST>:[380]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh group>
<2011-09-08 10:07:35.822 CEST>:[381]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- PIN-Email mapping rows changed: 0>
<2011-09-08 10:07:35.822 CEST>:[382]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh user group membership>
<2011-09-08 10:07:35.822 CEST>:[383]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- HTTP Proxy Mappings>
<2011-09-08 10:07:35.822 CEST>:[384]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh media management>
<2011-09-08 10:07:35.822 CEST>:[385]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Access Control data>
<2011-09-08 10:07:35.822 CEST>:[386]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh IT policy>
<2011-09-08 10:07:35.822 CEST>:[387]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, existing RSA option = 0  , new RSA option = 0>
<2011-09-08 10:07:35.822 CEST>:[388]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task Finished>
<2011-09-08 10:08:33.259 CEST>:[389]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 10:08:33.259 CEST>:[390]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[56]>
<2011-09-08 10:09:33.258 CEST>:[391]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 10:09:33.258 CEST>:[392]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[56]>
<2011-09-08 10:10:33.257 CEST>:[393]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 10:10:33.257 CEST>:[394]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[56]>
<2011-09-08 10:11:33.256 CEST>:[395]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S13100005[LVKBB:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2011-09-08 10:11:33.256 CEST>:[396]:<MDS-CS_LVKBB_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S13100005[LVKBB:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[56]>

 

Any comments or ideas are highly appreciated :-)

New Contributor
marevens
Posts: 5
Registered: ‎03-22-2011
My Device: none

Re: Problems with custom java app running on device

I just added a close-statement to the writer as well, just after flushing..

writer.flush();
writer.close();

 

I see from the sample code on http://supportforums.blackberry.com/t5/Java-Development/Close-connections/ta-p/444878 that they are using HttpConnection, like this:

 

conn = (HttpConnection)Connector.open

 

 

while my code is with StreamConnection, like this:

 

conn = (StreamConnection) Connector.open(URL);

 

Maybe I should try that?