server0 process in yellow and in status "starting apps" from many times

[antonio_voce]

Hi to all,

platform windows/SQL

I have a problem , I just made a system copy of my BI-Java (NW 7.0 SR3 ) using database independent system copy method.

I make the export : all OK.

I make the import ( system copy tool sapinst ) : All ok without errors.

Now I'm tryng to start the Java ap server ( ABAP IS UP AND WORKS ) but it doesn't start : in MMC I see the java server0 process in yellow and in status "starting apps" from many times.

Please help , what can I do ?

[former_member189546 · Accepted solution]

Hello,

Please check your Java parameters with this note

741289 Profile parameters of the J2EE Engine are lost

regards,

John Feely

[antonio_voce · Accepted solution]

Hi ,

My java instance is a separated instance from abap , it is not an add-in so this note is not applicable for me.

[antonio_voce · Accepted solution]

Hi ,

I solve the problem , the solution I adopted was to copy the Java.Parameters in the offline config tools from the source system !

tks to all Antonio

[Former Member]

Hi,

If you have an antivirus with on access scan, you may try to deactivate it during the starting of the Java stack.

On my systems, it halves the startup time which can be very very long for the first time (up to 45 minutes with antivirus, up to 20 minutes without antivirus).

Regards,

Olivier

[former_member204746]

wait 20 minutes.

if it is still yellow, check contents from dev_server0 log file

[antonio_voce]

I Eric ,

I waited , but still yellow :

here is the dev_server0 , it is very very long file so i post most important parts of the log

-

---

trc file: "F:\usr\sap\JBX\JC00\work\dev_server0", trc level: 1, release: "700"

-

---

node name : ID5856350

pid : 2460

system name : JBX

system nr. : 00

started at : Mon Nov 15 18:32:02 2010

arguments :

arg[00] : F:\usr\sap\JBX\JC00\exe\jlaunch.exe

arg[01] : pf=F:\usr\sap\JBX\SYS\profile\JBX_JC00_sappino

arg[02] : -DSAPINFO=JBX_00_server

arg[03] : pf=F:\usr\sap\JBX\SYS\profile\JBX_JC00_sappino

[Thr 1676] Mon Nov 15 18:32:02 2010

[Thr 1676] *** WARNING => INFO: Unknown property [instance.box.number=JBXJC00sappino] [jstartxx.c 841]

[Thr 1676] *** WARNING => INFO: Unknown property [instance.en.host=sappino] [jstartxx.c 841]

[Thr 1676] *** WARNING => INFO: Unknown property [instance.en.port=3201] [jstartxx.c 841]

[Thr 1676] *** WARNING => INFO: Unknown property [instance.system.id=0] [jstartxx.c 841]

**********************************************************************

JStartupReadInstanceProperties: read instance properties [F:\usr\sap\JBX\JC00\j2ee\cluster\instance.properties]

-> ms host : sappino

-> ms port : 3901

-> OS libs : F:\usr\sap\JBX\JC00\j2ee\os_libs

-> Admin URL :

-> run mode : normal

-> run action : NONE

-> enabled : yes

**********************************************************************

**********************************************************************

Used property files

-> files [00] : F:\usr\sap\JBX\JC00\j2ee\cluster\instance.properties

**********************************************************************

**********************************************************************

Instance properties

-> ms host : sappino

-> ms port : 3901

-> os libs : F:\usr\sap\JBX\JC00\j2ee\os_libs

-> admin URL :

-> run mode : normal

-> run action : NONE

-> enabled : yes

**********************************************************************

**********************************************************************

Bootstrap nodes

-> [00] bootstrap : F:\usr\sap\JBX\JC00\j2ee\cluster\instance.properties

-> [01] bootstrap_ID5856300 : F:\usr\sap\JBX\JC00\j2ee\cluster\instance.properties

-> [02] bootstrap_ID5856350 : F:\usr\sap\JBX\JC00\j2ee\cluster\instance.properties

**********************************************************************

**********************************************************************

Worker nodes

-> [00] ID5856300 : F:\usr\sap\JBX\JC00\j2ee\cluster\instance.properties

-> [01] ID5856350 : F:\usr\sap\JBX\JC00\j2ee\cluster\instance.properties

**********************************************************************

[Thr 1676] JLaunchRequestQueueInit: create named pipe for ipc

[Thr 1676] JLaunchRequestQueueInit: create pipe listener thread

[Thr 2796] JLaunchRequestFunc: Thread 2796 started as listener thread for np messages.

[Thr 1116] WaitSyncSemThread: Thread 1116 started as semaphore monitor thread.

[Thr 1676] Mon Nov 15 18:32:03 2010

[Thr 1676] NiInit3: NI already initialized; param 'maxHandles' ignored (1;202)

[Thr 1676] CPIC (version=700.2006.09.13)

[Thr 1676] [Node: server0] java home is set by profile parameter

Java Home: C:\JAVA_SDK_64BIT

[Thr 1676] JStartupICheckFrameworkPackage: can't find framework package F:\usr\sap\JBX\JC00\exe\jvmx.jar

**********************************************************************

JStartupIReadSection: read node properties [ID5856350]

-> node name : server0

-> node type : server

-> node execute : yes

-> jlaunch parameters :

-> java path : C:\JAVA_SDK_64BIT

-> java parameters : -Djco.jarm=1 -verbose:gc -Djava.awt.headless=true -Dsun.io.useCanonCaches=false -Djava.security.policy=./java.policy -Djava.security.egd=file:/dev/urandom -Dorg.omg.CORBA.ORBClass=com.sap.engine.system.ORBProxy -Dorg.omg.CORBA.ORBSingletonClass=com.sap.engine.system.ORBSingletonProxy -Djavax.rmi.CORBA.PortableRemoteObjectClass=com.sap.engine.system.PortableRemoteObjectProxy -XX:NewSize=341m -XX:MaxNewSize=341m -XX:MaxPermSize=192m -XX:PermSize=192m -XX:DisableExplicitGC -XX:PrintGCDetails -XX:+PrintGCTimeStamps -XX:SurvivorRatio=2 -XX:TargetSurvivorRatio=90 -XX:SoftRefLRUPolicyMSPerMB=1 -verbose:gc

-> java vm version : 1.4.2_26-b03

-> java vm vendor : Java HotSpot(TM) 64-Bit Server VM (Sun Microsystems Inc.)

-> java vm type : server

-> java vm cpu : amd64

-> heap size : 2048M

-> init heap size : 2048M

-> stack size : 2M

-> root path : F:\usr\sap\JBX\JC00\j2ee\cluster\server0

-> class path : .\bin\boot\boot.jar;.\bin\boot\jaas.jar;.\bin\system\bytecode.jar;.

-> OS libs path : F:\usr\sap\JBX\JC00\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 : F:\usr\sap\JBX\JC00\exe\jstartup.jar;F:\usr\sap\JBX\JC00\exe\jvmx.jar

-> shutdown class : com.sap.engine.boot.Start

-> parameters :

-> debuggable : no

-> debug mode : no

-> debug port : 50021

-> shutdown timeout : 120000

**********************************************************************

[Thr 1676] JLaunchISetDebugMode: set debug mode [no]

[Thr 2372] JLaunchIStartFunc: Thread 2372 started as Java VM thread.

[Thr 2372] [JHVM_PrepareVMOptions] use java parameters set by profile parameter

Java Parameters: -Xss2m

**********************************************************************

JHVM_LoadJavaVM: VM Arguments of node [server0]

-> stack : 1048576 Bytes

-> arg[ 0]: exit

-> arg[ 1]: abort

-> arg[ 2]: vfprintf

-> arg[ 3]: -Djco.jarm=1

-> arg[ 4]: -verbose:gc

-> arg[ 5]: -Djava.awt.headless=true

-> arg[ 6]: -Dsun.io.useCanonCaches=false

-> arg[ 7]: -Djava.security.policy=./java.policy

-> arg[ 8]: -Djava.security.egd=file:/dev/urandom

-> arg[ 9]: -Dorg.omg.CORBA.ORBClass=com.sap.engine.system.ORBProxy

-> arg[ 10]: -Dorg.omg.CORBA.ORBSingletonClass=com.sap.engine.system.ORBSingletonProxy

-> arg[ 11]: -Djavax.rmi.CORBA.PortableRemoteObjectClass=com.sap.engine.system.PortableRemoteObjectProxy

-> arg[ 12]: -XX:NewSize=341m

-> arg[ 13]: -XX:MaxNewSize=341m

-> arg[ 14]: -XX:MaxPermSize=192m

-> arg[ 15]: -XX:PermSize=192m

-> arg[ 16]: -XX:+DisableExplicitGC

-> arg[ 17]: -XX:+PrintGCDetails

-> arg[ 18]: -XX:+PrintGCTimeStamps

-> arg[ 19]: -XX:SurvivorRatio=2

-> arg[ 20]: -XX:TargetSurvivorRatio=90

-> arg[ 21]: -XX:SoftRefLRUPolicyMSPerMB=1

-> arg[ 22]: -verbose:gc

-> arg[ 23]: -Dsys.global.dir=F:\usr\sap\JBX\SYS\global

-> arg[ 24]: -Dapplication.home=F:\usr\sap\JBX\JC00\exe

-> arg[ 25]: -Djava.class.path=F:\usr\sap\JBX\JC00\exe\jstartup.jar;F:\usr\sap\JBX\JC00\exe\jvmx.jar;.\bin\boot\boot.jar;.\bin\boot\jaas.jar;.\bin\system\bytecode.jar;.

-> arg[ 26]: -Djava.library.path=C:\JAVA_SDK_64BIT\jre\bin\server;C:\JAVA_SDK_64BIT\jre\bin;C:\JAVA_SDK_64BIT\bin;F:\usr\sap\JBX\JC00\j2ee\os_libs;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\WINDOWS\system32\WindowsPowerShell\v1.0;C:\Program Files\Microsoft SQL Server\90\Tools\binn\;C:\Program Files (x86)\Microsoft SQL Server\90\Tools\binn\;C:\Program Files (x86)\Microsoft SQL Server\90\DTS\Binn\;C:\Program Files (x86)\Microsoft SQL Server\90\Tools\Binn\VSShell\Common7\IDE\;c:\Program Files\Microsoft SQL Server\90\DTS\Binn\;C:\WINDOWS\system32\WindowsPowerShell\v1.0;F:\usr\sap\JBX\SYS\exe\uc\NTAMD64

-> arg[ 27]: -Dmemory.manager=2048M

-> arg[ 28]: -Xmx2048M

-> arg[ 29]: -Xms2048M

-> arg[ 30]: -Xss2M

-> arg[ 31]: -DLoadBalanceRestricted=no

-> arg[ 32]: -Djstartup.mode=JCONTROL

-> arg[ 33]: -Djstartup.ownProcessId=2460

-> arg[ 34]: -Djstartup.ownHardwareId=M1883233260

-> arg[ 35]: -Djstartup.whoami=server

-> arg[ 36]: -Djstartup.debuggable=no

-> arg[ 37]: -Xss2m

-> arg[ 38]: -DSAPINFO=JBX_00_server

-> arg[ 39]: -DSAPSTARTUP=1

-> arg[ 40]: -DSAPSYSTEM=00

-> arg[ 41]: -DSAPSYSTEMNAME=JBX

-> arg[ 42]: -DSAPMYNAME=sappino_JBX_00

-> arg[ 43]: -DSAPDBHOST=SAPPINO

-> arg[ 44]: -Dj2ee.dbhost=SAPPINO

**********************************************************************

CompilerOracle: exclude com/sapportals/portal/pb/layout/taglib/ContainerTag addIviewResources

CompilerOracle: exclude com/sap/engine/services/keystore/impl/security/CodeBasedSecurityConnector getApplicationDomain

CompilerOracle: exclude com/sap/engine/services/rmi_p4/P4StubSkeletonGenerator generateStub

CompilerOracle: exclude com/sapportals/portal/prt/util/StringUtils escapeToJS

CompilerOracle: exclude com/sapportals/portal/prt/core/broker/PortalServiceItem startServices

CompilerOracle: exclude com/sap/engine/services/webservices/server/deploy/WSConfigurationHandler downloadFile

CompilerOracle: exclude com/sapportals/portal/prt/jndisupport/util/AbstractHierarchicalContext lookup

CompilerOracle: exclude com/sapportals/portal/navigation/cache/CacheNavigationNode getAttributeValue

CompilerOracle: exclude com/sapportals/portal/navigation/TopLevelNavigationiView PrintNode

CompilerOracle: exclude com/sapportals/wcm/service/ice/wcm/ICEPropertiesCoder encode

CompilerOracle: exclude com/sap/lcr/pers/delta/importing/ObjectLoader loadObjects

CompilerOracle: exclude com/sap/engine/services/webservices/jaxrpc/encoding/InstanceBuilder readElement

CompilerOracle: exclude com/sap/engine/services/webservices/jaxrpc/encoding/InstanceBuilder readSequence

CompilerOracle: exclude com/sap/engine/services/webservices/jaxrpc/encoding/TypeMappingImpl initializeRelations

CompilerOracle: exclude com/sap/engine/services/webservices/jaxrpc/encoding/GeneratedComplexType _loadInto

[Thr 2372] Mon Nov 15 18:32:04 2010

[Thr 2372] JHVM_LoadJavaVM: Java VM created OK.

**********************************************************************

JHVM_BuildArgumentList: main method arguments of node [server0]

**********************************************************************

[Thr 3844] Mon Nov 15 18:32:07 2010

[Thr 3844] JHVM_RegisterNatives: registering methods in com.sap.bc.krn.perf.PerfTimes

[Thr 3844] Mon Nov 15 18:32:08 2010

[Thr 3844] JHVM_RegisterNatives: registering methods in com.sap.bc.proj.jstartup.JStartupFramework

[Thr 3844] Mon Nov 15 18:32:09 2010

[Thr 3844] JLaunchISetClusterId: set cluster id 5856350

[Thr 3844] JLaunchISetState: change state from [Initial (0)] to [Waiting for start (1)]

[Thr 3844] JLaunchISetState: change state from [Waiting for start (1)] to [Starting (2)]

Mon Nov 15 18:33:09 2010

66.769: [GC 66.779: [DefNew

Mon Nov 15 18:33:12 2010

: 174592K->12719K(261888K), 2.4968787 secs] 174592K->12719K(2009856K), 2.5007114 secs]

Mon Nov 15 18:33:28 2010

85.012: [GC 85.019: [DefNew

Mon Nov 15 18:33:31 2010

: 187311K->15802K(261888K), 2.9950002 secs] 187311K->15802K(2009856K), 2.9962968 secs]

Mon Nov 15 18:33:42 2010

99.362: [GC 99.363: [DefNew

Mon Nov 15 18:33:43 2010

: 190394K->18114K(261888K), 1.3817075 secs] 190394K->18114K(2009856K), 1.3817727 secs]

Mon Nov 15 18:34:04 2010

121.270: [GC 121.270: [DefNew: 192706K->22709K(261888K), 0.4353252 secs] 192706K->22709K(2009856K), 0.4354424 secs]

Mon Nov 15 18:34:14 2010

131.077: [GC 131.079: [DefNew

Mon Nov 15 18:34:15 2010

: 197301K->29493K(261888K), 0.9813876 secs] 197301K->29493K(2009856K), 0.9823920 secs]

[Thr 3344] Mon Nov 15 18:34:36 2010

[Thr 3344] JHVM_RegisterNatives: registering methods in com.sap.security.core.server.vsi.service.jni.VirusScanInterface

Mon Nov 15 18:34:44 2010

160.965: [GC 160.966: [DefNew

Mon Nov 15 18:34:47 2010

: 204085K->40068K(261888K), 3.8189779 secs] 204085K->40068K(2009856K), 3.8194810 secs]

[Thr 3532] Mon Nov 15 18:34:54 2010

[Thr 3532] JHVM_RegisterNatives: registering methods in com.sap.mw.rfc.driver.CpicDriver

[Thr 4932] Mon Nov 15 18:34:57 2010

[Thr 4932] JHVM_RegisterNatives: registering methods in com.sap.i18n.cp.ConverterJNI

Mon Nov 15 18:35:09 2010

186.312: [GC 186.315: [DefNew: 214660K->44513K(261888K), 0.4742509 secs] 214660K->44513K(2009856K), 0.4773469 secs]

[Thr 3844] Mon Nov 15 18:38:32 2010

[Thr 3844] JLaunchISetState: change state from [Starting (2)] to [Starting applications (10)]

Mon Nov 15 18:39:18 2010

435.575: [GC 435.576: [DefNew: 219105K->48184K(261888K), 0.2394759 secs] 219105K->48184K(2009856K), 0.2397480 secs]

Mon Nov 15 18:39:20 2010

1. Excluding compile: com.sap.engine.services.webservices.jaxrpc.encoding.TypeMappingImpl::initializeRelations

Mon Nov 15 18:39:23 2010

440.506: [GC 440.506: [DefNew: 222776K->50686K(261888K), 0.1335379 secs] 222776K->50686K(2009856K), 0.1335965 secs]

Mon Nov 15 19:42:07 2010

[Unloading class sun.reflect.GeneratedMethodAccessor14]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor127]

[Unloading class sun.reflect.GeneratedMethodAccessor13]

[Unloading class sun.reflect.GeneratedMethodAccessor105]

[Unloading class sun.reflect.GeneratedMethodAccessor99]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor135]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor4]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor96]

[Unloading class sun.reflect.GeneratedMethodAccessor128]

Mon Nov 15 19:42:08 2010

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor17]

[Unloading class sun.reflect.GeneratedMethodAccessor119]

[Unloading class sun.reflect.GeneratedMethodAccessor64]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor29]

[Unloading class sun.reflect.GeneratedMethodAccessor47]

[Unloading class sun.reflect.GeneratedMethodAccessor86]

[Unloading class sun.reflect.GeneratedConstructorAccessor72]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor14]

[Unloading class sun.reflect.GeneratedMethodAccessor109]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor82]

[Unloading class sun.reflect.GeneratedMethodAccessor156]

[Unloading class sun.reflect.GeneratedMethodAccessor147]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor94]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor65]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor93]

Mon Nov 15 19:42:09 2010

[Unloading class sun.reflect.GeneratedMethodAccessor107]

[Unloading class sun.reflect.GeneratedMethodAccessor142]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor2]

Mon Nov 15 20:19:06 2010

: 1488916K->1046521K(1747968K), 3204.1629115 secs] 1749638K->1046521K(2009856K), 3204.1631834 secs]

[antonio_voce]

th log continue and continue with this messages :

Mon Nov 15 19:42:07 2010

[Unloading class sun.reflect.GeneratedMethodAccessor14]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor127]

[Unloading class sun.reflect.GeneratedMethodAccessor13]

[Unloading class sun.reflect.GeneratedMethodAccessor105]

[Unloading class sun.reflect.GeneratedMethodAccessor99]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor135]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor4]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor96]

[Unloading class sun.reflect.GeneratedMethodAccessor128]

Mon Nov 15 19:42:08 2010

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor17]

[Unloading class sun.reflect.GeneratedMethodAccessor119]

[Unloading class sun.reflect.GeneratedMethodAccessor64]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor29]

[Unloading class sun.reflect.GeneratedMethodAccessor47]

[Unloading class sun.reflect.GeneratedMethodAccessor86]

[Unloading class sun.reflect.GeneratedConstructorAccessor72]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor14]

[Unloading class sun.reflect.GeneratedMethodAccessor109]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor82]

[Unloading class sun.reflect.GeneratedMethodAccessor156]

[Unloading class sun.reflect.GeneratedMethodAccessor147]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor94]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor65]

[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor93]

[Former Member]

Looks like issue with garbage collection.

>>[GC 66.779: [DefNew Mon Nov 15 18:33:12 2010 : 174592K->12719K(261888K), 2.4968787 secs] 174592K->12719K(2009856K), 2.5007114 secs] Mon Nov 15 18:33:28 2010 85.012:

I suggest you to have a look on below links.

/people/rudolf.meier/blog/2005/05/11/implications-of-java-garbage-collection-for-multi-user-applications

/people/rudolf.meier/blog/2005/07/01/implications-of-java-garbage-collection-for-multi-user-applications--part-ii

Have you configured Introscope Wily, if not then please do so where you can monitor your system performance. There is a document on marketplace how to configure / monitor instance under wily.