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. Note the Weblogic classes in the stack trace.

18:31:20.403 VERB t@3888 stateless dispatch for allocExternalContext.bosInterface 
18:31:20.403 VERB t@3888   input params: sessionId=EjkwM6TREKL5aMad2wqsGH8tYghLNO3vXE2JZJzRRUpOZrKkpuZg!-1522304197!1155753072277:mx28136748f556a9de:(__emxnavigator.java:959), stackTrace=
	at matrix.db.Context.getContext(Context.java:923)
	at matrix.db.Context.CloneContext(Context.java:332)
	at matrix.db.Context.<init>(Context.java:263)
	at com.matrixone.servlet.FrameworkServlet.getFrameContext(FrameworkServlet.java:535)
	at com.matrixone.servlet.FrameworkServlet.getFrameContext(FrameworkServlet.java:527)
	at com.matrixone.servlet.Framework.getFrameContext(Framework.java:365)
	at jsp_servlet._common.__emxnavigator._jspService(__emxnavigator.java:959)
	at weblogic.servlet.jsp.JspBase.service(JspBase.java:33) 
18:31:20.403 VERB t@3888 dispatch complete since 18:31:20.403, 0.000 secs (0.000 direct 0.000 nested) 
... 

19:32:19.625 VERB t@2048 allocate context for session EjkwM6TREKL5aMad2wqsGH8tYghLNO3vXE2JZJzRRUpOZrKkpuZg!-1522304197!1155753072277:mx100322343aaee3:(__emxnavigatortoolbar.java:1165) 
19:32:19.625 VERB t@2048   input params: className=emxPropertyUtil, construct length=0, methodName=cacheSymbolicNames, params length=1 
19:32:19.625 VERB t@2048   input stacktrace: 
	at com.matrixone.jdl.bosInterfaceShim.invokeClass(bosInterfaceShim.java:957)
	at matrix.db.JPO.invokePrivate(JPO.java:213)
	at matrix.db.JPO.invoke(JPO.java:132)
	at com.matrixone.apps.domain.util.PropertyUtil.loadSymbolicNamesCache(PropertyUtil.java:4 03)
	at com.matrixone.apps.domain.util.PropertyUtil.checkSymbolicNamesCache(PropertyUtil.java: 379)
	at jsp_servlet._ematrix._emxhome._jspService(_emxhome.java:506)
	at weblogic.servlet.jsp.JspBase.service(JspBase.java:27)

Note that there are no Weblogic 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: