Example of Studio Customization Toolkit Origin Trace Ouput

MX_VERBOSE_TRACE processing prints the session ID and stack trace string. The following is sample output from a JSP page.

17:15:46.909 VERB t@17516 stateful dispatch for get_user.bosContext
17:15:46.909 VERB t@17516 session 854C4842C0549E3618F6D42815C415B5:mx14410921240:func_frame_context_utThread-13
17:15:46.909 VERB t@17516   input params:   (session=854C4842C0549E3618F6D42815C415B5:mx14410921240:func_frame_context_utThread-13)
17:15:46.910 VERB t@17516     stacktrace: 
       at com.matrixone.jdl.rmi.bosContextImpl.get_user(bosContextImpl.java:81)
       at com.matrixone.jdl.MatrixXMLDispatch.dispatch_bosContext(MatrixXMLDispatch.java:617)
       at com.matrixone.jdl.MatrixXMLDispatch.dispatch(MatrixXMLDispatch.java:76)
       at com.matrixone.servlet.MatrixXMLServlet.doPost(MatrixXMLServlet.java:225)
       at javax.servlet.http.HttpServlet.service(HttpServlet.java:650)
       at javax.servlet.http.HttpServlet.service(HttpServlet.java:731)
       at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303)
       at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
       at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
       at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
       at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
       at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
       at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
       at org.apache.tomee.catalina.OpenEJBValve.invoke(OpenEJBValve.java:44)
       at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:505)
       at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:170)
       at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:103)
       at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:957)
       at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
       at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:423)
       at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1079)
       at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:620)
       at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:316)
       at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
       at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
       at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
       at java.lang.Thread.run(Thread.java:745)
17:15:46.910 VERB t@17516   output params: user=
17:15:46.910 VERB t@17516 dispatch complete since 17:15:46.909, 0.001 secs (0.001 direct 0.000 nested)

Note that there are no Tomcat classes in the stack trace.

15:52:49.109 VERB t@2544 allocate context for session EvcL6qZfvtlNMKrC2q3O33lKBmevRTQtNa5MSmBWb0Kps2IdbX2j!1231146595!1156521163062:mx4350123950b68a7:(__emxnavigator.java:959) 
15:52:49.109 VERB t@2544   input params: className=emxPropertyUtil_mxJPOzXZnZQAAAAEAAAAB 
15:52:49.109 VERB t@2544     stacktrace: 
	at com.matrixone.jdl.bosInterfaceShim.classLoader(Unknown Source)
	at matrix.db.MatrixClassLoader.getBytes(MatrixClassLoader.java:67)
	at matrix.db.MatrixClassLoader.findClass(MatrixClassLoader.java:86)
	at java.lang.ClassLoader.loadClass(ClassLoader.java:294)
	at matrix.db.MatrixClassLoader.newClass(MatrixClassLoader.java:167)
	at matrix.db.MatrixClassLoader.newInstance(MatrixClassLoader.java:197)
	at matrix.db.MatrixClassLoader.newInstance(MatrixClassLoader.java:292)
	at com.matrixone.jni.MatrixKernel.statelessDispatch(Native Method)
	at com.matrixone.jdl.rmi.bosInterfaceImpl.invokeClass(Unknown Source)
	at java.lang.reflect.Method.invoke(Native Method)
	at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:236)
	at sun.rmi.transport.Transport$1.run(Transport.java:147)
	at java.security.AccessController.doPrivileged(Native Method)
	at sun.rmi.transport.Transport.serviceCall(Transport.java:143)
	at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:460)
	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:701)
	at java.lang.Thread.run(Thread.java:479) 
15:52:49.140 VERB t@2544   output params: returnVal length=365 
15:52:49.140 VERB t@2544 dispatch complete since 15:52:49.109, 0.031 secs (0.031 direct 0.000 nested)

Verbose tracing output can include the caller information appended to the context session ID generated by Framework.getFrameContext(). The following is an example illustrating this.

Note that the caller information emxteamsearchcontentresult.java follows the string "B1yyHxgoYeuyPHpaAPAH2qLIBGplw42XKR5B228B2Oki2UJNLuKH!-682390697!16 7842110!7001!7002!1102426802906:mx1102427172562633055".

13:46:13.390 VERB t@1776 stateless dispatch for evaluateSelect.bosQuery 
13:46:13.390 VERB t@1776 allocate context for session B1yyHxgoYeuyPHpaAPAH2qLIBGplw42XKR5B228B2Oki2UJNLuKH!-682390697!167842110!7001!7002!11 02426802906:mx1102427172562633055:(__emxteamsearchcontentresult.java:3628) 
13:46:13.390 VERB t@1776   input params: name=, query type=Document, name=*, revision=*, lattice=eService Production, owner=*, where=(revision == last) && (("to[Vaulted Objects].from.id" !~~ "zz")) && (current.access[read] == TRUE), limit=0, expandTypes=true 
, objectSelect length=2 
13:46:13.437 VERB t@1776   output params: