Monitoring Servers

Sample Output

Note: Be aware that the format of the below information will change, possibly significantly, in future releases.

***************************************************
eMatrix Server Trace - Version 10.6.2.0 - 11/21/2005 18:18:13 GMT:
>>>>
>>>> Tells how long the server has been up.
>>>>
eMatrix Active: 1 hour 5 minutes
>>>>
>>>> Thread #1 entered the server as user creator to evaluate a and has
>>>> been doing so for 0 seconds. Query fields are provided
>>>>
Thread #1: t@053, ref=0x485b98
  session=mx113259838319617870931
  ms=78e2fe00x08
<Start Stack Trace>
  <1 - ADK Verbose Trace Entry>
  stateless dispatch for evaluateSelect.bosQuery executing
  Active: 0 seconds
  parameters:
    bosContext _cntx:
      user:
        creator
      depth:
        1
      session id:
        mx113259838319617870931
    bosString _name:
      
    bosQuerySt _query:
      bosQueryClauseSt clause
        bosString typePattern
          CaseOutline
        bosString namePattern
          *
        bosString revisionPattern
          *
        bosString latticePattern
          *
        bosString ownerPattern
          *
        bosString where
          name matchlist "*mon-yen,*tue-yng" "," && ((current ~= 
"Active") && ("attribute[RAPTestName]" ~~ "**") && ("attribute[Last 
Name]" ~~ "**") && ("relationship[Employee].from.name" ~= "Acme 
Technologies"))
      bosString fullTextSearch
        
      bosString fullTextFormat
        
      short limit
        100
      uint8 expandTypes
        1
      uint8 queryTrigger
        0
      bosString description
        
      bosDataList icon
        0 entries
    bosStringList _objectSelect:
      5 entries
          bosString str
            attribute[First Name]
          bosString str
            attribute[Last Name]
          bosString str
            name
          bosString str
            id
          bosString str
            relationship[CaseOutlineToRAPPackageData].from
      stacktrace: 
    at 
com.MatrixOne.jdl.bosQueryShim.evaluateSelect(bosQueryShim.java:102)
    at matrix.db.Query.select(Query.java:821)
    at ContextTest.doQuery(ContextTest.java:109)
    at ContextTest.run(ContextTest.java:64)
>>>>
>>>> If there are any warnings/errors that have occurred in this thread
>>>> they will be shown here.
>>>>
Error Stack:
        Warning #1500568: Where clause is not valid; contains term 
'attribute[Last Name]' referring to a non-existent attribute.
<End Stack Trace>
>>>>
>>>> Thread #2 entered the server as user creator to execute an mql 
command 
>>>> and has been doing so for 0 seconds. 
>>>> The mql command is provided - 'exec prog JPOTest'
>>>>
Thread #2: t@024, ref=0x36e268
  session=mx1132597948702605645
  ms=1a3a380x08
<Start Stack Trace>
  <1 - ADK Verbose Trace Entry>
  stateless dispatch for executeCmd.bosMQLCommand executing
  Active: 0 seconds
  parameters:
    bosContext _cntx:
      user:
        creator
      depth:
        3
      session id:
        mx1132597948702605645
    bosString _cmd:
      exec prog JPO_Test
      stacktrace: 
    at 
com.MatrixOne.jdl.bosMQLCommandShim.executeCmd(bosMQLCommandShim.java:
32)
    at matrix.db.MQLCommand.executeCommand(MQLCommand.java:90)
    at DiagTestProg.run(DiagTestProg.java:55)
  Error Stack:
<2 - Executing MQL Command>
  Active: 0 seconds
  cmd=exec prog JPO_Test
  Error Stack:
>>>>
>>>> entries 3,4 show the launching of the program
>>>>
<3 - Launching Program Object>
  Active: 0 seconds
    Name=JPO_Test, user=, invocation=program
  Global env at program launch:
  Local env at program launch:
    APPLICATION=BOS
    INVOCATION=program
    MATRIXHOME=/local/home/dvlp/ksmith/rmi10550
    MATRIXPATH=
    PATH=/usr/j2se/bin:/local/home/dvlp/ksmith/rmi10550/bin/solaris4:/
usr/dt/bin:/usr/openwin/bin:/usr/openwin/bin/xview:/sbin:/bin:/usr/
bin:/usr/sbin:/opt/SUNWspro/prod/bin:/usr/ucb:/usr/ccs/bin:/etc:/usr/
etc:/usr/etc/install:.:/sqa/netlib:/usr/local/bin:/usr/local/bin/X11:/
app/qausr/matrix-install/scripts:/app/verity/k2/k2/_ssol26/bin
  Deferred:
    false
  Error Stack:
  
  <4 - Launching Java Object>
  Active: 0 seconds
  progName:
    JPO_Test
  constructor parameters: 
  className:
    JPO_Test_mxJPOA9VrXAAAAAEAAAAG
  methodName:
    mxMain
  method parameters:
  
  Error Stack:
>>>>
>>>> The program calls back through the ADK interface to execute 
another MQL
>>>> command - 'monitor server'
>>>>
<5 - ADK Verbose Trace Entry>
  stateless dispatch for executeCmd.bosMQLCommand executing
  Active: 0 seconds
  parameters:
    bosContext _cntx:
      user:
        creator
      depth:
        3
      session id:
        mx1132597948702605645
    bosString _cmd:
      monitor server
>>>>
>>>> And finally, the java stack trace that led to the initial ADK call 
is also 
>>>> provided.(This is not provided by default - ADK Origin trace must 
be enabled)
>>>>
stacktrace: 
    at 
com.MatrixOne.jdl.bosMQLCommandShim.executeCmd(bosMQLCommandShim.java:
32)
    at matrix.db.MQLCommand.executeCommand(MQLCommand.java:90)
    at 
JPO_Test_mxJPOA9VrXAAAAAEAAAAG.mxMain(JPO_Test_mxJPOA9VrXAAAAAEAAAAG.j
ava:25)
    at java.lang.reflect.Method.invoke(Native Method)
    at 
matrix.db.MatrixClassLoader.invokeObject(MatrixClassLoader.java:383)
    at 
matrix.db.MatrixClassLoader.invokeObject(MatrixClassLoader.java:400)
    at com.MatrixOne.jni.MatrixKernel.statelessDispatch(Native Method)
    at 
com.MatrixOne.jdl.rmi.bosMQLCommandImpl.executeCmd(bosMQLCommandImpl.j
ava:46)
    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:46
0)
    at 
sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.
java:701)
    at java.lang.Thread.run(Thread.java:479)
>>>>
>>>> No errors for Thread #2
>>>>
Error Stack:
  
  <End Stack Trace>
>>>>
>>>> Thread #3 is also executing an MQL command - 'promote bus PART-u 
Test 0'
>>>>
Thread #3: t@021, ref=0x36e2e0
  session=mx113259964028925613505
  ms=42f1b80x08
  <Start Stack Trace>
  <1 - ADK Verbose Trace Entry>
  stateless dispatch for executeCmd.bosMQLCommand executing
  Active: 4.00 seconds 
  parameters:
    bosContext _cntx:
      user:
        creator
      depth:
        1
      session id:
        mx113259964028925613505
    bosString _cmd:
      promote bus PART-u Test 0;
  
  Error Stack:
        Warning #1500318: The ConnectString in your connection 
(bootstrap) file does not match the ConnectString of any server object 
defined in the database
  <2 - Executing MQL Command>
  Active: 4.00 seconds 
  cmd=promote bus PART-u Test 0;
  Error Stack:
>>>>
>>>> The promotion launches a trigger.  Trigger macros and RPE values 
are shown
>>>>
<3 - Launching Trigger Program>
  Active: 4.00 seconds 
    TriggerState
    active=true
    owner Oid=212401520
    name=JPO_DiagTest
    args=
    info=PromoteCheck
  Error Stack:
  
  <4 - Launching Program Object>
  Active: 4.00 seconds 
    Name=JPO_DiagTest, user=, invocation=check
  Global env at program launch:
  Local env at program launch:
    ACCESSFLAG=True
    APPLICATION=BOS
    AUTOPROMOTE=False
    CHECKACCESSFLAG=True
    CURRENTSTATE=one
    DESCRIPTION=
    ENFORCEDLOCKINGFLAG=False
    EVENT=Promote
    HASACTUALDATE=True
    HASSCHEDULEDATE=False
    INVOCATION=check
    ISCURRENT=True
    ISDISABLED=False
    ISENABLED=True
    ISOVERRIDDEN=False
    ISREVISIONABLE=True
    ISVERSIONABLE=True
    LATTICE=unit1
    LOCKER=
    LOCKFLAG=False
    MATRIXHOME=/local/home/dvlp/kkohn/rmi10550
    MATRIXPATH=
    NAME=Test
    NEXTSTATE=final
    OBJECT="PART-u" "Test" "0"
    OBJECTID=32541.36365.3240.64880
    OWNER=creator
    PATH=/usr/j2se/bin:/local/home/dvlp/kkohn/rmi10550/bin/solaris4:/
usr/dt/bin:/usr/openwin/bin:/usr/openwin/bin/xview:/sbin:/bin:/usr/
bin:/usr/sbin:/opt/SUNWspro/prod/bin:/usr/ucb:/usr/ccs/bin:/etc:/usr/
etc:/usr/etc/install:.:/sqa/netlib:/usr/local/bin:/usr/local/bin/X11:/
app/qausr/matrix-install/scripts:/app/verity/k2/k2/_ssol26/bin
    POLICY=simple-u
    REVISION=0
    STATENAME=one
    TIMESTAMP=Mon Nov 21, 2005 2:01:30 PM EST
    TRIGGER_VAULT=unit1
    TYPE=PART-u
    USER=creator
    VAULT=unit1
  Deferred:
    false
  Error Stack:
  
  <5 - Launching Java Object>
  Active: 4.00 seconds 
  progName:
    JPO_DiagTest
  constructor parameters: 
  className:
    JPO_DiagTest_mxJPOAeDjNAAAAAEAAAAC
  methodName:
    mxMain
  method parameters:
>>>>
>>>> No errors for Thread #3
>>>>
Error Stack:
<End Stack Trace>
Monitored 3 active eMatrix threads
***************************************************

Since it passes large strings across the ENOVIA Studio Customization Toolkit connection for every server call, thereby affecting performance, ENOVIA Studio Customization Toolkit Origin trace output is not included in server monitor output by default. However, if diagnostic efforts would be significantly aided by knowing the appserver-tier Java stack (JSP's and beans) that initiated certain ENOVIA Studio Customization Toolkit calls, it can be useful, and is therefore included in server monitor output when enabled.

With the introduction of Flash DB, ENOVIA Live Collaboration supports multi/hyper threaded CPUs. Hyper-threading support provides better utilization of multi-core CPUs which are becoming common place. This support introduces background threads that do work which, in prior releases, occurred in a single threaded fashion. The result is improved performance and higher throughput. The threads consume very little memory and are transparent to the JVM and/or application server.

Server monitor output has been enhanced to include these threads, as in the example below:

>>>>
>>>> Thread #1 is the parent thread of "Background Task" example below.
>>>>
Thread #1: t@155, ref=0x855400, 
session=mx16717111951ee8e:mx1688703977961823, ms=0x01e28c00
<Start Stack Trace>
  <1 - ADK Verbose Trace Entry>
  stateless dispatch for executeCmd.bosMQLCommand executing
  Active: 0 seconds
  User:
    creator
  Session:
    mx16717111951ee8e:mx1688703977961823
  Parameters:
    bosContext _cntx:
      user:
        creator
      depth:
        1
      session id:
        mx16717111951ee8e:mx1688703977961823
    bosString _cmd:
      list prog
  Error Stack:
>>>>
>>>> ADK call MQLCommand issued "list prog"
>>>>
<2 - Executing MQL Command>
  Active: 0 seconds
  Command:
    list prog
  Error Stack:
  
<End Stack Trace>
Thread #2: t@158, ref=0x80f918, 
session=mx16717111951ee8e:mx1688703977961823, ms=0x01e28c00
<Start Stack Trace>
  <1 - Launching Background Task>
  Active: 0 seconds
>>>>
>>>> Task type: "FlashNextTask" indicates cache is being read. Parent 
thread id is found 
>>>> in thread #1 above.
>>>>
Task type: FlashNextTask
  Parent Thread: t@155  
  Priority: high
  Reference count: 2
  Error Stack:
  
<End Stack Trace>
Monitored 2 active threads

The defined background task types are:

• SessionTask—generic task
• ExitTask—session exit task
• SyncTask—administration task
• InsertTask—database insert task
• UpdateTask—database update task
• SQLNextTask—database read task
• FlashNextTask—FlashDB cache management task
• IdCacheTask—object ID management task