[size=20]trc file: "dev_dispatcher", trc level: 2, release: "720"
---------------------------------------------------
node name   : ID208186800
pid         : 21792
system name : KSM
system nr.  : 20
started at  : Sun Feb 24 15:55:16 2013
arguments         :
          arg[00] : /usr/sap/KSM/DVEBMGS20/exe/jlaunch
          arg[01] : pf=/usr/sap/KSM/SYS/profile/KSM_DVEBMGS20_sap-ci-ksm
          arg[02] : -DSAPINFO=KSM_20_dispatcher
          arg[03] : pf=/usr/sap/KSM/SYS/profile/KSM_DVEBMGS20_sap-ci-ksm
          arg[04] : -DSAPSTART=1


**********************************************************************
Used property files
-> files [00] : /usr/sap/KSM/DVEBMGS20/j2ee/cluster/instance.properties
**********************************************************************

**********************************************************************
Bootstrap nodes
-> [00] bootstrap            : /usr/sap/KSM/DVEBMGS20/j2ee/cluster/instance.properties
-> [01] bootstrap_ID20818680 : /usr/sap/KSM/DVEBMGS20/j2ee/cluster/instance.properties
-> [02] bootstrap_ID20818685 : /usr/sap/KSM/DVEBMGS20/j2ee/cluster/instance.properties
**********************************************************************

**********************************************************************
Worker nodes
-> [00] ID208186800          : /usr/sap/KSM/DVEBMGS20/j2ee/cluster/instance.properties
-> [01] ID208186850          : /usr/sap/KSM/DVEBMGS20/j2ee/cluster/instance.properties
**********************************************************************

[Thr 47950828765536] Sun Feb 24 15:55:16 2013
[Thr 47950828765536] JStartupFreeInstanceProperties: free instance properties
[Thr 47950828765536] JStartupReadInstanceProperties: read instance properties from /usr/sap/KSM/DVEBMGS20/j2ee/cluster/instance.properties
[Thr 47950828765536] JStartupReadInstanceProperties: enabled yes
[Thr 47950828765536] Deprecated property [instance.ms.host = sap-ci-ksm]
[Thr 47950828765536] Deprecated property [instance.ms.port = 3921]
[Thr 47950828765536] JStartupReadInstanceProperties: OS library path /usr/sap/KSM/DVEBMGS20/j2ee/os_libs
[Thr 47950828765536] JStartupReadInstanceProperties: run action NONE
[Thr 47950828765536] JStartupReadInstanceProperties: run mode normal
[Thr 47950828765536] JStartupReadInstanceProperties: message server host sap-ci-ksm
[Thr 47950828765536] JStartupReadInstanceProperties: message server port 3921

**********************************************************************
JStartupReadInstanceProperties: read instance properties [/usr/sap/KSM/DVEBMGS20/j2ee/cluster/instance.properties]
-> ms host    : sap-ci-ksm
-> ms port    : 3921
-> OS libs    : /usr/sap/KSM/DVEBMGS20/j2ee/os_libs
-> Admin URL  :
-> run mode   : normal
-> run action : NONE
-> enabled    : yes
**********************************************************************

[Thr 47950828765536] JLaunchUnixLoadDLL: Preloading JVM DLL
[Thr 47950828765536] [Node: dispatcher] java home is set by profile parameter
[Thr 47950828765536]    Java Home: /usr/lib64/jvm/java
[Thr 47950828765536] SlicGetHwid called
[Thr 47950828765536] SlicGetHwId: sysname: Linux nodename: bl3 machine: x86_64
[Thr 47950828765536]              release: 2.6.16.60-0.39.3-smp version: #1 SMP Mon May 11 11:46:34 UTC 2009
[Thr 47950828765536] SlicLinuxHwid: found 6 interfaces
[Thr 47950828765536]   IF 0: Name=<lo> [Thr 47950828765536] Flags=<49>
[Thr 47950828765536]   IF 1: Name=<lo> [Thr 47950828765536] Flags=<49>
[Thr 47950828765536]   IF 2: Name=<eth0> [Thr 47950828765536] Flags=<1043>
[Thr 47950828765536] SlicLinuxHwid: ID<eth0> family=<2> IP-ADDR=<192.168.99.111>
[Thr 47950828765536] use this device
[Thr 47950828765536] SlicLinuxHwid: ID<eth0> family=<1> HW-ADDR=<0-14-4F-9E-8F-98>
[Thr 47950828765536] SlicIReadPciVendorList: reading /proc/bus/pci/devices
[Thr 47950828765536] SlicIReadPciVendorList: found id 0x10de
[Thr 47950828765536] SlicIReadPciVendorList: found id 0x1022
[Thr 47950828765536] SlicIReadPciVendorList: found id 0x1002
[Thr 47950828765536] SlicIReadPciVendorList: found id 0x1000
[Thr 47950828765536] SlicIReadPciVendorList: found id 0x10df
[Thr 47950828765536] SlicIReadPciVendorList: finished scan of '/proc/bus/pci/devices'
[Thr 47950828765536] SlicIReadHypervisorType: fopen(/sys/hypervisor/type) failed
[Thr 47950828765536] SlicGetVirtualization: no virtualization found
[Thr 47950828765536] SlicGetFullHwId: hwid '0-14-4F-9E-8F-98' found
[Thr 47950828765536] SlicGetHwId: ID >0-14-4F-9E-8F-98<
[Thr 47950828765536] SlicGetHwId: CUSTOMER KEY: >X2075931712<
[Thr 47950828765536] JStartupICheckFrameworkPackage: can't find framework package /usr/sap/KSM/DVEBMGS20/exe/jvmx.jar

**********************************************************************
JStartupIReadSection: read node properties [ID208186800]
-> node name          : dispatcher
-> node type          : dispatcher
-> node execute       : yes
-> jlaunch parameters :
-> java path          : /usr/lib64/jvm/java
-> java parameters    : -Xgcpolicy:gencon -verbose:gc -Djava.security.policy=./java.policy -Djava.security.egd=file:/dev/urandom -Djco.jarm=1 -Xmn70M
-> java vm version    : J2RE 1.4.2 IBM J9 2.3 Linux amd64-64 j9vmxa64142-20090310 (JIT enabled)
-> java vm vendor     : IBM J9 VM (IBM Corporation)
-> java vm type       : server
-> java vm cpu        : amd64
-> heap size          : 200M
-> init heap size     : 200M
-> root path          : /usr/sap/KSM/DVEBMGS20/j2ee/cluster/dispatcher
-> class path         : ./bin/boot/boot.jar:./bin/system/bytecode.jar:.
-> OS libs path       : /usr/sap/KSM/DVEBMGS20/j2ee/os_libs
-> main class         : com.sap.engine.boot.Start
-> framework class    : com.sap.bc.proj.jstartup.JStartupFramework
-> registr. class     : com.sap.bc.proj.jstartup.JStartupNatives
-> framework path     : /usr/sap/KSM/DVEBMGS20/exe/jstartup.jar:/usr/sap/KSM/DVEBMGS20/exe/jvmx.jar
-> shutdown class     : com.sap.engine.boot.Start
-> parameters         :
-> debuggable         : no
-> debug mode         : no
-> debug port         : 50000
-> shutdown timeout   : 120 sec.
**********************************************************************

[Thr 47950828765536] JHVM_LoadJNIFunctions: load JVM library [libjvm.so]
[Thr 47950828765536] DlLoadLib() success: dlopen("libjvm.so"), hdl 0
[Thr 47950828765536] JHVM_LoadJNIFunctions: load function [JNI_CreateJavaVM] from [libjvm.so]
[Thr 47950828765536] JHVM_LoadJNIFunctions: load function [JNI_GetDefaultJavaVMInitArgs] from [libjvm.so]
[Thr 47950828765536] JLaunchUnixLoadDLL: Preload of JVM DLL done
[Thr 47950828765536] JLaunchInitIPC: NiInit2(2048)
[Thr 47950828765536] NiIInit: allocated nitab (2048 at 2b9c6d710010)
[Thr 47950828765536] NiIHSBufInit: initialize hostname buffer (IPv4)
[Thr 47950828765536] NiHLInit: alloc host buf (100 entries)
[Thr 47950828765536] NiSrvLInit: alloc serv bufs (100 entries)
[Thr 47950828765536] Initialize Shared Memory Request Queue
[Thr 47950828765536] JLaunchRequestQueueInit: create named pipe for ipc
[Thr 47950828765536] JLaunchRequestQueueInit: create pipe listener thread
[Thr 1074792768] JLaunchRequestFunc: Thread 1074792768 started as listener thread for np messages.
[Thr 47950828765536] Attach to shared memory administration
[Thr 1083185472] WaitSyncSemThread: Thread 1083185472 started as semaphore monitor thread.
[Thr 47950828765536] NiInit3: NI already initializes (init=1;cur=2048)
[Thr 47950828765536] JsfOpenShm: Static shared memory opened ok at 0x2aaaaaabe000
[Thr 47950828765536] JsfAttachDynShm: dynamic shm attached ok at 0x2aaaaaabf000
[Thr 47950828765536] NiHLInit: alloc host buf (100 entries)
[Thr 47950828765536] NiSrvLInit: alloc serv bufs (100 entries)
[Thr 47950828765536] Initialize Shared Memory Request Queue
[Thr 47950828765536] JLaunchRequestQueueInit: create named pipe for ipc
[Thr 47950828765536] JLaunchRequestQueueInit: create pipe listener thread
[Thr 1074792768] JLaunchRequestFunc: Thread 1074792768 started as listener thread for np messages.
[Thr 47950828765536] Attach to shared memory administration
[Thr 1083185472] WaitSyncSemThread: Thread 1083185472 started as semaphore monitor thread.
[Thr 47950828765536] NiInit3: NI already initializes (init=1;cur=2048)
[Thr 47950828765536] JsfOpenShm: Static shared memory opened ok at 0x2aaaaaabe000
[Thr 47950828765536] JsfAttachDynShm: dynamic shm attached ok at 0x2aaaaaabf000
[Thr 47950828765536] SfICreateRatingData: created 5 LB instances.
[Thr 47950828765536] JLaunchInitSignalHandling (0)
[Thr 47950828765536] SigIReaction: Sig 1: set flags 0x00000000 -> 0x4000000d
[Thr 47950828765536] SigIReaction: Sig 2: set flags 0x00000000 -> 0x4000000d
[Thr 47950828765536] SigIReaction: Sig 4: set flags 0x00000000 -> 0x4000000d
[Thr 47950828765536] SigIReaction: Sig 5: set flags 0x00000000 -> 0x4000000d
[Thr 47950828765536] SigIReaction: Sig 6: set flags 0x00000000 -> 0x4000000d
[Thr 47950828765536] SigIReaction: Sig 7: set flags 0x00000000 -> 0x4000000d
[Thr 47950828765536] SigIReaction: Sig 8: set flags 0x00000000 -> 0x4000000d
[Thr 47950828765536] SigIReaction: Sig 10: set flags 0x00000000 -> 0x40000009
[Thr 47950828765536] SigIReaction: Sig 11: set flags 0x00000000 -> 0x4000000d
[Thr 47950828765536] SigIReaction: Sig 12: set flags 0x00000000 -> 0x40000009
[Thr 47950828765536] SigIReaction: Sig 13: set flags 0x04000000 -> 0x44000009
[Thr 47950828765536] SigIReaction: Sig 15: set flags 0x00000000 -> 0x4000000d
[Thr 47950828765536] SigIReaction: Sig 16: set flags 0x00000000 -> 0x4000000d
[Thr 47950828765536] SigIReaction: Sig 17: set flags 0x00000000 -> 0x40000009
[Thr 47950828765536] SigIReaction: Sig 21: set flags 0x00000000 -> 0x40000009
[Thr 47950828765536] SigIReaction: Sig 22: set flags 0x00000000 -> 0x40000009
[Thr 47950828765536] SigIReaction: Sig 23: set flags 0x00000000 -> 0x4000000d
[Thr 47950828765536] SigIReaction: Sig 24: set flags 0x00000000 -> 0x40000009
[Thr 47950828765536] SigIReaction: Sig 25: set flags 0x00000000 -> 0x40000009
[Thr 47950828765536] SigIReaction: Sig 26: set flags 0x00000000 -> 0x4000000d
[Thr 47950828765536] SigIReaction: Sig 28: set flags 0x00000000 -> 0x40000009
[Thr 47950828765536] SigIReaction: Sig 30: set flags 0x00000000 -> 0x4000000d
[Thr 47950828765536] SigIReaction: Sig 31: set flags 0x00000000 -> 0x4000000d
[Thr 47950828765536] SigIInit: ok (whoami=0)
[Thr 47950828765536] SigISetDefaultAction : default handling for signal SIGCHLD
[Thr 47950828765536] *************** SAP_STINIT ***************
[Thr 47950828765536] NiMyHostName: hostname = 'bl3'
[Thr 47950828765536] NiHLGetNodeAddr: got hostname 'bl3' from operating system
[Thr 47950828765536] NiIGetNodeAddr: hostname 'bl3' = addr 192.168.99.111
[Thr 47950828765536] NiInit3: NI already initialized; param 'maxHandles' ignored (init=2;par=32768;cur=2048)
[Thr 47950828765536] NiInit3: NI already initialized; param 'maxHandles' ignored (init=2;par=32768;cur=2048)
[Thr 47950828765536] NiMyHostName: hostname = 'bl3'
[Thr 47950828765536] NiHLGetNodeAddr: found hostname 'bl3' in cache
[Thr 47950828765536] NiIGetNodeAddr: hostname 'bl3' = addr 192.168.99.111
[Thr 47950828765536] NiSetParamEx: switch NIP_CONNLOCAL on
[Thr 47950828765536] JRFC> INIT: CPIC and RSCP have been initialized by jlaunch
[Thr 47950828765536] JRFC> Initialize jrfc mutex [bd4ae0]
[Thr 47950828765536] JRFC> INIT: initialized
[Thr 47950828765536] CCMS: Detected Double Stack (parameter "rdisp/j2ee_start" is active)
[Thr 47950828765536] CCMS: SemInMgt: Semaphore Management initialized by AlAttachShm_Doublestack.
[Thr 47950828765536] CCMS: SemInit: Semaphore 38 initialized by AlAttachShm_Doublestack.
[Thr 47950828765536] CCMS: CCMS Monitoring Initialization finished, rc=0.
[Thr 47950828765536] [Node: dispatcher] java home is set by profile parameter
[Thr 47950828765536]    Java Home: /usr/lib64/jvm/java
[Thr 47950828765536] JStartupICheckFrameworkPackage: can't find framework package /usr/sap/KSM/DVEBMGS20/exe/jvmx.jar

**********************************************************************
JStartupIReadSection: read node properties [ID208186800]
-> node name          : dispatcher
-> node type          : dispatcher
-> node execute       : yes
-> jlaunch parameters :
-> java path          : /usr/lib64/jvm/java
-> java parameters    : -Xgcpolicy:gencon -verbose:gc -Djava.security.policy=./java.policy -Djava.security.egd=file:/dev/urandom -Djco.jarm=1 -Xmn70M
-> java vm version    : J2RE 1.4.2 IBM J9 2.3 Linux amd64-64 j9vmxa64142-20090310 (JIT enabled)
-> java vm vendor     : IBM J9 VM (IBM Corporation)
-> java vm type       : server
-> java vm cpu        : amd64
-> heap size          : 200M
-> init heap size     : 200M
-> root path          : /usr/sap/KSM/DVEBMGS20/j2ee/cluster/dispatcher
-> class path         : ./bin/boot/boot.jar:./bin/system/bytecode.jar:.
-> OS libs path       : /usr/sap/KSM/DVEBMGS20/j2ee/os_libs
-> main class         : com.sap.engine.boot.Start
-> framework class    : com.sap.bc.proj.jstartup.JStartupFramework
-> registr. class     : com.sap.bc.proj.jstartup.JStartupNatives
-> framework path     : /usr/sap/KSM/DVEBMGS20/exe/jstartup.jar:/usr/sap/KSM/DVEBMGS20/exe/jvmx.jar
-> shutdown class     : com.sap.engine.boot.Start
-> parameters         :
-> debuggable         : no
-> debug mode         : no
-> debug port         : 50000
-> shutdown timeout   : 120 sec.

[Thr 47950828765536] JLaunchIGetMemSize ("1048576")
[Thr 47950828765536] JLaunchIGetMemSize: no scaling factor found in "1048576".
[Thr 47950828765536] JLaunchIGetMemSize (1048576) = 1048576
[Thr 47950828765536] JLaunchISetDebugMode: set debug mode [no]
[Thr 47950828765536] NiHLGetNodeAddr: got hostname 'localhost' from operating system
[Thr 47950828765536] NiIGetNodeAddr: hostname 'localhost' = addr 127.0.0.1
[Thr 47950828765536] NiIGetNodeAddr: hostname 'NULL' = addr 127.0.0.1
[Thr 1084238144] JLaunchIStartFunc: Thread 1084238144 started as Java VM thread.
[Thr 1084238144] JLaunchIChangeDirectory: change working directory [/usr/sap/KSM/DVEBMGS20/j2ee/cluster/dispatcher]

**********************************************************************
JHVM_LoadJavaVM: VM Arguments of node [dispatcher]
-> stack   : 1048576 Bytes
-> arg[  0]: exit
-> arg[  1]: abort
-> arg[  2]: vfprintf
-> arg[  3]: -Xgcpolicy:gencon
-> arg[  4]: -verbose:gc
-> arg[  5]: -Djava.security.policy=./java.policy
-> arg[  6]: -Djava.security.egd=file:/dev/urandom
-> arg[  7]: -Djco.jarm=1
-> arg[  8]: -Xmn70M
-> arg[  9]: -Dsys.global.dir=/usr/sap/KSM/SYS/global
-> arg[ 10]: -Dapplication.home=/usr/sap/KSM/DVEBMGS20/exe
-> arg[ 11]: -Djava.class.path=/usr/sap/KSM/DVEBMGS20/exe/jstartup.jar:/usr/sap/KSM/DVEBMGS20/exe/jvmx.jar:./bin/boot/boot.jar:./bin/system/bytecode.jar:.
-> arg[ 12]: -Djava.library.path=/usr/lib64/jvm/java-1_4_2-ibm-1.4.2/jre/bin/j9vm:/usr/lib64/jvm/java-1_4_2-ibm-1.4.2/jre/bin:/usr/lib64/jvm/java-1_4_2-ibm-1.4.2/jre/bin:/usr/lib64/jvm/java-1_4_2-ibm-1.4.2/jre/bin/classic:/usr/sap/KSM/DVEBMGS20/exe:/usr/sap/KSM/DVEBMGS20/exe:/usr/sap/KSM/DVEBMGS20/exe:/usr/sap/KSM/SYS/exe/run:/oracle/client/10x_64/instantclient:/usr/lib:/usr/sap/KSM/DVEBMGS20/j2ee/os_libs:/usr/sap/KSM/DVEBMGS20/j2ee/os_libs:/usr/sap/KSM/DVEBMGS20/exe:/usr/sap/KSM/SYS/exe/run:/oracle/client/10x_64/instantclient
-> arg[ 13]: -Dmemory.manager=200M
-> arg[ 14]: -Xmx200M
-> arg[ 15]: -Xms200M
-> arg[ 16]: -DLoadBalanceRestricted=no
-> arg[ 17]: -Djstartup.mode=JCONTROL
-> arg[ 18]: -Djstartup.ownProcessId=21792
-> arg[ 19]: -Djstartup.ownHardwareId=X2075931712
-> arg[ 20]: -Djstartup.whoami=dispatcher
-> arg[ 21]: -Djstartup.debuggable=no
-> arg[ 22]: -DSAPINFO=KSM_20_dispatcher
-> arg[ 23]: -DSAPSTART=1
-> arg[ 24]: -DSAPSTARTUP=1
-> arg[ 25]: -DSAPSYSTEM=20
-> arg[ 26]: -DSAPSYSTEMNAME=KSM
-> arg[ 27]: -DSAPMYNAME=sap-ci-ksm_KSM_20
-> arg[ 28]: -DSAPDBHOST=sap-ci-ksm
-> arg[ 29]: -Dj2ee.dbhost=sap-ci-ksm
**********************************************************************


<?xml version="1.0" ?>

<verbosegc version="200902_24">

[Thr 1084238144] JHVM_LoadJavaVM: Java VM created OK.
[Thr 1084238144] JHVM_RegisterFramework: registering native registration method.
[Thr 1094211904] JVMC_MonitoringThread: Monitoring Thread started

**********************************************************************
JHVM_BuildArgumentList: main method arguments of node [dispatcher]
**********************************************************************

[Thr 1084238144] JHVM_RedirectStreams: redirect JVM output into file /usr/sap/KSM/DVEBMGS20/work/jvm_dispatcher.out
[Thr 1094744384] Sun Feb 24 15:55:19 2013
[Thr 1094744384] JHVM_RegisterNatives: com.sap.bc.krn.perf.PerfTimes
[Thr 1094744384] *** INFO => JHVM_RegisterNatives: some native methods in com.sap.bc.krn.perf.PerfTimes not registered.
[Thr 1094744384] Method analysis:
[Thr 1094744384] OK     : com.sap.bc.krn.perf.PerfTimes nativeInfo ((I)I)
[Thr 1094744384] OK     : com.sap.bc.krn.perf.PerfTimes init (()I)
[Thr 1094744384] OK     : com.sap.bc.krn.perf.PerfTimes getStampUs (()J)
[Thr 1094744384] OK     : com.sap.bc.krn.perf.PerfTimes getDeltaUs ((J)J)
[Thr 1094744384] OK     : com.sap.bc.krn.perf.PerfTimes getDelta2Us ((JJ)J)
[Thr 1094744384] OK     : com.sap.bc.krn.perf.PerfTimes elapsed (()J)
[Thr 1094744384] OK     : com.sap.bc.krn.perf.PerfTimes cpuTimeUs (()J)
[Thr 1094744384] OK     : com.sap.bc.krn.perf.PerfTimes cpuTimePerThread (()Z)
[Thr 1094744384] FAILED : com.sap.bc.krn.perf.PerfTimes processCpuTimeUs (()J)
[Thr 1094744384] JHVM_RegisterNatives: 8 of 9 methods in com.sap.bc.krn.perf.PerfTimes registered.
[Thr 1094744384] Sun Feb 24 15:55:20 2013
[Thr 1094744384] JHVM_RegisterNatives: com.sap.bc.proj.jstartup.JStartupFramework
[Thr 1094744384] JHVM_NativeGetParam: parameter jstartup/proxy not found
[Thr 1094744384] JHVM_NativeSetClusterId: set cluster id 208186800
[Thr 1094744384] JLaunchISetClusterId: set cluster id 208186800
[Thr 1094744384] JHVM_NativeGetParam: SAPLOCALHOST=sap-ci-ksm
[Thr 1094744384] JHVM_NativeSetState: set process state to 1
[Thr 1094744384] JLaunchISetState: change state from [Initial (0)] to [Waiting for start (1)]
[Thr 1094744384] JHVM_NativeSetState: set process state to 2
[Thr 1094744384] JLaunchISetState: change state from [Waiting for start (1)] to [Starting (2)]
[Thr 1094744384] JHVM_NativeGetParam: parameter jstartup/proxy not found
Sun Feb 24 15:55:21 2013
<af type="nursery" id="1" timestamp="Feb 24 15:55:21 2013" intervalms="0.000">
  <minimum requested_bytes="4120" />
  <time exclusiveaccessms="0.163" />
  <nursery freebytes="0" totalbytes="36700160" percent="0" />
  <tenured freebytes="133047840" totalbytes="136314880" percent="97" >
    <soa freebytes="126232096" totalbytes="129499136" percent="97" />
    <loa freebytes="6815744" totalbytes="6815744" percent="100" />
  </tenured>
  <gc type="scavenger" id="1" totalid="1" intervalms="0.000">
    <flipped objectcount="52008" bytes="4217816" />
    <tenured objectcount="0" bytes="0" />
    <refs_cleared soft="30" weak="2" phantom="0" />
    <finalization objectsqueued="45" />
    <scavenger tiltratio="50" />
    <nursery freebytes="32255912" totalbytes="36700160" percent="87" tenureage="10" />
    <tenured freebytes="133047840" totalbytes="136314880" percent="97" >
      <soa freebytes="126232096" totalbytes="129499136" percent="97" />
      <loa freebytes="6815744" totalbytes="6815744" percent="100" />
    </tenured>
    <time totalms="19.366" />
  </gc>
  <nursery freebytes="32251792" totalbytes="36700160" percent="87" />
  <tenured freebytes="133047840" totalbytes="136314880" percent="97" >
    <soa freebytes="126232096" totalbytes="129499136" percent="97" />
    <loa freebytes="6815744" totalbytes="6815744" percent="100" />
  </tenured>
  <time totalms="19.833" />
[/size]