Java processdid not reach state PARTRUNNING after 20:00 minutes. Giving up.

[Former Member]

Hi all!

After installing Dialog instance J2ee i recieve error "Java process did not reach state PARTRUNNING after 20:00 minutes. Giving up." And after restarting in SystemInfo i see "Dispatcher running, server0 starting application".

Logs from ../work

dev_server0

-

---

trc file: "/usr/sap/NPP/J27/work/dev_server0", trc level: 1, release: "700"

-

---

node name : ID272494050

pid : 463056

system name : NPP

system nr. : 27

started at : Tue Oct 28 13:43:04 2008

arguments :

arg[00] : /usr/sap/NPP/J27/exe/jlaunch

arg[01] : pf=/usr/sap/NPP/SYS/profile/NPP_J27_standby

arg[02] : -DSAPINFO=NPP_27_server

arg[03] : pf=/usr/sap/NPP/SYS/profile/NPP_J27_standby

[Thr 1] Tue Oct 28 13:43:04 2008

[Thr 1] *** WARNING => INFO: Unknown property [instance.box.number=NPPJ27standby] [jstartxx_mt. 841]

[Thr 1] *** WARNING => INFO: Unknown property [instance.en.host=sapnode] [jstartxx_mt. 841]

[Thr 1] *** WARNING => INFO: Unknown property [instance.en.port=3222] [jstartxx_mt. 841]

[Thr 1] *** WARNING => INFO: Unknown property [instance.system.id=27] [jstartxx_mt. 841]

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

JStartupReadInstanceProperties: read instance properties [/usr/sap/NPP/J27/j2ee/cluster/instance.properties]

-> ms host : sapnode

-> ms port : 3922

-> OS libs : /usr/sap/NPP/J27/j2ee/os_libs

-> Admin URL :

-> run mode : NORMAL

-> run action : NONE

-> enabled : yes

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

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

Used property files

-> files [00] : /usr/sap/NPP/J27/j2ee/cluster/instance.properties

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

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

Instance properties

-> ms host : sapnode

-> ms port : 3922

-> os libs : /usr/sap/NPP/J27/j2ee/os_libs

-> admin URL :

-> run mode : NORMAL

-> run action : NONE

-> enabled : yes

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

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

Bootstrap nodes

-> [00] bootstrap : /usr/sap/NPP/J27/j2ee/cluster/instance.properties

-> [01] bootstrap_ID27249400 : /usr/sap/NPP/J27/j2ee/cluster/instance.properties

-> [02] bootstrap_ID27249405 : /usr/sap/NPP/J27/j2ee/cluster/instance.properties

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

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

Worker nodes

-> [00] ID272494000 : /usr/sap/NPP/J27/j2ee/cluster/instance.properties

-> [01] ID272494050 : /usr/sap/NPP/J27/j2ee/cluster/instance.properties

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

[Thr 1] JLaunchRequestQueueInit: create named pipe for ipc

[Thr 1] JLaunchRequestQueueInit: create pipe listener thread

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

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

[Thr 1] SigISetDefaultAction : default handling for signal 20

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

[Thr 1] Tue Oct 28 13:43:09 2008

[Thr 1] CPIC (version=700.2005.12.02)

[Thr 1] *** WARNING => Maximum Java heap size specified twice (through maxHeapSize and in javaParameters) - using -Xmx2048m [jstartxx_mt. 2604]

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

Java Home: /usr/java14_64

[Thr 1] JStartupICheckFrameworkPackage: can't find framework package /usr/sap/NPP/J27/exe/jvmx.jar

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

JStartupIReadSection: read node properties [ID272494050]

-> node name : server0

-> node type : server

-> node execute : yes

-> jlaunch parameters :

-> java path : /usr/java14_64

-> java parameters : -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 -Dorg.xml.sax.driver=com.sap.engine.lib.xml.parser.SAXParser -verbose:gc -Djco.jarm=1 -Xj9 -Xgcpolicy:gencon -Xmn400m -Xtrace -Xsoftrefthreshold3 -Drdbms.driverLocation=/oracle/client/10x_64/instantclient/ojdbc14.jar

-> java vm version : J2RE 1.4.2 IBM J9 2.3 AIX ppc64-64 j9ap64142-20080510 (JIT enabled)

-> java vm vendor : IBM J9 VM (IBM Corporation)

-> java vm type : server

-> java vm cpu : ppc64

-> heap size : 2048M

-> init heap size : 2048M

-> root path : /usr/sap/NPP/J27/j2ee/cluster/server0

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

-> OS libs path : /usr/sap/NPP/J27/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/NPP/J27/exe/jstartup.jar:/usr/sap/NPP/J27/exe/jvmx.jar

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

-> parameters :

-> debuggable : no

-> debug mode : no

-> debug port : 52721

-> shutdown timeout : 120000

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

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

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

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

JHVM_LoadJavaVM: VM Arguments of node [server0]

-> stack : 1048576 Bytes

-> arg[ 0]: exit

-> arg[ 1]: abort

-> arg[ 2]: vfprintf

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

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

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

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

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

-> arg[ 8]: -Dorg.xml.sax.driver=com.sap.engine.lib.xml.parser.SAXParser

-> arg[ 9]: -verbose:gc

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

-> arg[ 11]: -Xj9

-> arg[ 12]: -Xgcpolicy:gencon

-> arg[ 13]: -Xmn400m

-> arg[ 14]: -Xtrace

-> arg[ 15]: -Xsoftrefthreshold3

-> arg[ 16]: -Drdbms.driverLocation=/oracle/client/10x_64/instantclient/ojdbc14.jar

-> arg[ 17]: -Dsys.global.dir=/usr/sap/NPP/SYS/global

-> arg[ 18]: -Dapplication.home=/usr/sap/NPP/J27/exe

-> arg[ 19]: -Djava.class.path=/usr/sap/NPP/J27/exe/jstartup.jar:/usr/sap/NPP/J27/exe/jvmx.jar:./bin/boot/boot.jar:./bin/boot/jaas.jar:./bin/system/bytecode.jar:.

-> arg[ 20]: -Djava.library.path=/usr/java14_64/jre/bin:/usr/java14_64/jre/bin:/usr/java14_64/jre/bin/classic:/usr/java14_64/jre/bin:/usr/sap/NPP/J27/exe:/usr/sap/NPP/J27/exe:/tmp/sapinst_exe.438410.1225187636:/usr/sap/NPP/SYS/exe/run:/oracle/client/10x_64/instantclient:/usr/java14_64/jre/bin/j9vm:/usr/sap/NPP/J27/exe::/usr/lib:/usr/sap/NPP/J27/j2ee/os_libs:/usr/sap/NPP/J27/exe:/usr/sap/NPP/J27/exe:/tmp/sapinst_exe.438410.1225187636:/usr/sap/NPP/SYS/exe/run:/oracle/client/10x_64/instantclient

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

-> arg[ 22]: -Xmx2048M

-> arg[ 23]: -Xms2048M

-> arg[ 24]: -DLoadBalanceRestricted=no

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

-> arg[ 26]: -Djstartup.ownProcessId=463056

-> arg[ 27]: -Djstartup.ownHardwareId=D0693002222

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

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

-> arg[ 30]: -DSAPINFO=NPP_27_server

-> arg[ 31]: -DSAPSTARTUP=1

-> arg[ 32]: -DSAPSYSTEM=27

-> arg[ 33]: -DSAPSYSTEMNAME=NPP

-> arg[ 34]: -DSAPMYNAME=standby_NPP_27

-> arg[ 35]: -DSAPDBHOST=sapnode

-> arg[ 36]: -Dj2ee.dbhost=sapnode

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

[Thr 772] Tue Oct 28 13:43:10 2008

[Thr 772] JHVM_LoadJavaVM: Java VM created OK.

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

JHVM_BuildArgumentList: main method arguments of node [server0]

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

[Thr 6684] Tue Oct 28 13:43:13 2008

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

[Thr 6684] Tue Oct 28 13:47:04 2008

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

[Thr 6684] JLaunchISetClusterId: set cluster id 272494050

[Thr 6684] Tue Oct 28 13:47:14 2008

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

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

[Thr 21334] Tue Oct 28 13:52:58 2008

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

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

[Thr 6684] Tue Oct 28 13:54:02 2008

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

[Thr 258] Tue Oct 28 15:34:57 2008

[Thr 258] JLaunchRequestFunc: receive command:17, argument:0 from pid:299226

[Thr 258] JLaunchIShutdownInvoke: set shutdown interval (stop:1225197297/end:1225197417/TO:120)

[Thr 258] JLaunchProcessCommand: Invoke VM Shutdown

[Thr 258] JHVM_FrameworkShutdownDirect: invoke direct shutdown

[Thr 20171] Tue Oct 28 15:35:17 2008

[Thr 20171] JLaunchISetState: change state from [Starting applications (10)] to [Waiting for stop (4)]

[Thr 20171] JLaunchISetState: change state from [Waiting for stop (4)] to [Stopping (5)]

[Thr 1] Tue Oct 28 15:36:58 2008

[Thr 1] *** WARNING => Reached shutdown timeout -> terminate [jlnchxxi_mt. 358]

[Thr 1] SigISetIgnoreAction : SIG_IGN for signal 20

[Thr 1] JLaunchCloseProgram: good bye (exitcode = -4)

[Former Member]

what does the log dev_jcontrol say?

[Former Member]

Hi,

It seems that you donot have sufficient RAM .....The Java process consumes lots of RAM while installation..

Please increase and continue the process..

Regards

[Former Member]

32Gb RAM on system available