Solved: SAP Netweaver Java 7.5 installation Java engine no...

michael_swistowski · ‎2016 Jul 11

Hello,

Im installing the Netweaver 7.5 Java Server.

However during the installation in Provisioning Manager when Java engine is starting I get an error. I tried the installation many times, Upgrade SAP kernel after the error, changing the /etc/hosts file like in the note 1713021 but the error is still the same:

--------------------------------------------------------------------------------

stdout/stderr redirection

--------------------------------------------------------------------------------

node name : server0

host name : sap-netweaver

system name : MII

system nr. : 00

started at : Fri Jul 8 11:21:59 2016

JVMX version - Nov 13 2015 10:11:59 - 81_REL - optU - linux amd64 - 6 - bas2:257478 (mixed mode)

(CompilerOracle read from file /usr/sap/MII/J00/exe/sapjvm_8/jre/.hotspot_compiler)

The active factory is : com.sap.engine.boot.loader.StandardClassLoaderFactory

Class loading cache information loaded from package index [/usr/sap/MII/J00/j2ee/cluster/bin/index.list]

Reading manager properties from [kernelProperties.bin] file

Checked the data for [10] ms

Objects read for [108] ms

Substituted the values for [75] ms

Finished reading kernel objects for [183] ms

Startup mode [NORMAL] and action [NONE] read

AS Java version [7.50.3301.382056.20150709124709 SP 00] is starting server process [ID5621950] on host xxx ...

Loading [LogManager]... [72] ms

Loading [PoolManager]... [3] ms

Loading [ApplicationThreadManager]... 1,875: [GC (Allocation Failure) 1,875: [ParNew: 1027405K->4447K(1223040K), 0,0251419 secs] 1027405K->4447K(4019584K), 0,0253897 secs] [Times: user=0,08 sys=0,01, real=0,02 secs]

[199] ms

Loading [ThreadManager]... [27] ms

Loading [ClassLoaderManager]... [18] ms

Loading [ClusterManager]... [1337] ms

Loading [LockingManager]... [47] ms

Loading [ConfigurationManager]... [1527] ms

Loading [CacheManager]... [125] ms

Loading [DatabaseManager]... [348] ms

Loading [SessionManager]... [319] ms

Loading [LicensingManager]... [41] ms

Loading [ServiceManager]...

Initialize components for : 122 ms.

5,901: [GC (CMS Initial Mark) 5,901: [GC (CMS Initial Mark) 5,901: [ParNew: 403005K->18674K(1223040K), 0,0351900 secs] 403005K->18674K(4019584K), 0,0353602 secs] [Times: user=0,12 sys=0,02, real=0,04 secs]

[1 CMS-initial-mark: 0K(2796544K)] 18674K(4019584K), 0,0409253 secs] [Times: user=0,14 sys=0,02, real=0,05 secs]

5,942: [CMS-concurrent-mark-start]

5,972: [CMS-concurrent-mark: 0,030/0,030 secs] [Times: user=0,06 sys=0,02, real=0,02 secs]

5,972: [CMS-concurrent-preclean-start]

5,980: [CMS-concurrent-preclean: 0,008/0,008 secs] [Times: user=0,02 sys=0,00, real=0,01 secs]

5,981: [CMS-concurrent-abortable-preclean-start]

Resolve components for : 32 ms.

Load components for 1027 ms.

Loading services.

Calculate startup service set for 282 ms.

Service [tc~je~security~api] started. [16] ms / [0] CPU ms

Service [engine.dsr.facade] started. [16] ms / [0] CPU ms

Service [tc~je~cross~api] started. [16] ms / [0] CPU ms

Service [tc~je~mngt_model~srv] started. [16] ms / [0] CPU ms

Service [r3startup] started. [20] ms / [0] CPU ms

Service [security.destination.facade] started. [21] ms / [0] CPU ms

Service [webservices] started. [21] ms / [0] CPU ms

Service [engine.lm.log.facade] started. [21] ms / [0] CPU ms

Service [tc~je~content~classification~handler] started. [22] ms / [0] CPU ms

Service [tc~esi~uddi~content~handler] started. [30] ms / [0] CPU ms

Service [tc~je~sca~ws.plugin] started. [26] ms / [0] CPU ms

Service [runtimeinfo] started. [100] ms / [8] CPU ms

Service [tc~je~sca~jco.plugin] started. [131] ms / [2] CPU ms

Service [cluster] started. [139] ms / [4] CPU ms

Service [memory] started. [367] ms / [19] CPU ms

Service [jmx_notification] started. [351] ms / [22] CPU ms

Service [timeout] started. [370] ms / [30] CPU ms

Service [userstore] started. [373] ms / [19] CPU ms

Service [tc~com.sap.service.jco] started. [388] ms / [142] CPU ms

Service [tc~sec~destinations~provider] started. [436] ms / [37] CPU ms

Service [cross] started. [426] ms / [52] CPU ms

Service [log_configurator] started. [504] ms / [53] CPU ms

Service [locking] started. [43] ms / [7] CPU ms

Service [tc~bl~accounting_infra~srv] started. [712] ms / [121] CPU ms

Service [p4] started. [376] ms / [80] CPU ms

Service [classload] started. [15] ms / [1] CPU ms

Service [msp] started. [15] ms / [3] CPU ms

Service [classload.facade] started. [0] ms / [0] CPU ms

Service [classpath_resolver] started. [53] ms / [15] CPU ms

Service [naming] started. [443] ms / [230] CPU ms

Service [file] started. [3] ms / [2] CPU ms

Service [tc~je~sca~ejb.plugin] started. [40] ms / [1] CPU ms

Service [objectanalyzing] started. [49] ms / [10] CPU ms

Service [javamail] started. [176] ms / [52] CPU ms

Service [connector] started. [211] ms / [55] CPU ms

Service [library_container] started. [29] ms / [12] CPU ms

Service [schemaprocessor~srv] started. [1869] ms / [899] CPU ms

Service [tc~je~sca~integration] started. [1662] ms / [1273] CPU ms

Service [http] started. [2777] ms / [223] CPU ms

CMS: abort preclean due to time 11,164: [CMS-concurrent-abortable-preclean: 3,956/5,183 secs] [Times: user=15,59 sys=0,93, real=5,19 secs]

11,164: [GC (CMS Final Remark) [YG occupancy: 378535 K (1223040 K)]11,164: [Rescan (parallel) , 0,0631602 secs]11,228: [weak refs processing, 0,0005210 secs]11,228: [class unloading, 0,0083460 secs]11,237: [scrub symbol table, 0,0167750 secs]11,253: [scrub string table, 0,0028361 secs][1 CMS-remark: 0K(2796544K)] 378535K(4019584K), 0,0936930 secs] [Times: user=0,30 sys=0,00, real=0,09 secs]

11,258: [CMS-concurrent-sweep-start]

11,259: [CMS-concurrent-sweep: 0,000/0,000 secs] [Times: user=0,00 sys=0,00, real=0,00 secs]

11,259: [CMS-concurrent-reset-start]

11,320: [CMS-concurrent-reset: 0,062/0,062 secs] [Times: user=0,13 sys=0,04, real=0,06 secs]

Service [deploy] started. [10104] ms / [429] CPU ms

Service [deploy.facade] started. [0] ms / [0] CPU ms

Service [MigrationService] started. [30] ms / [14] CPU ms

Service [orpersistence] started. [36] ms / [17] CPU ms

Service [metadatarepository] started. [40] ms / [8] CPU ms

Service [engine.migration.facade] started. [0] ms / [0] CPU ms

Service [engine.migservice.facade] started. [0] ms / [0] CPU ms

Service [dbpool] started. [1308] ms / [260] CPU ms

Service [dbpool.facade] started. [0] ms / [0] CPU ms

Service [tc~je~dbcontainer~srv] started. [145] ms / [28] CPU ms

Service [ts] started. [278] ms / [47] CPU ms

Service [licensing] started. [6] ms / [3] CPU ms

Service [engine.license.facade] started. [0] ms / [0] CPU ms

Service [tc~lm~nzdm~crrsrv] start ================= ERROR =================

CSN Component [BC-UPG-DTM-TLJ], DC Name [sap.com/tc~lm~nzdm~crrsrv]

java.lang.RuntimeException: Unable to look up data source jdbc/SAP/BC_NZDM

at com.sap.tc.lm.nzdm.crr.srv.core.NZDMServiceFrame.start(NZDMServiceFrame.java:105)

at com.sap.engine.frame.ApplicationFrameAdaptor.start(ApplicationFrameAdaptor.java:31)

at com.sap.engine.core.service630.container.ServiceRunner.startFrame(ServiceRunner.java:165)

at com.sap.engine.core.service630.container.ServiceRunner.startService(ServiceRunner.java:118)

at com.sap.engine.core.service630.container.ServiceRunner.run(ServiceRunner.java:65)

at com.sap.engine.frame.core.thread.Task.run(Task.java:73)

at com.sap.engine.core.thread.impl5.SingleThread.execute(SingleThread.java:178)

at com.sap.engine.core.thread.impl5.SingleThread.run(SingleThread.java:316)

FATAL: Critical shutdown with exit code [2150] was requested due to [AS Java cannot be started; core service [tc~lm~nzdm~crrsrv] failed to start]

Heap

par new generation reserved 1397760K, committed 1397760K, used 652460K [0x00000006f0000000, 0x0000000745500000, 0x0000000745500000)

eden space 1048320K, 60% used [0x00000006f0000000, 0x0000000716aee7c0, 0x000000072ffc0000)

from space 174720K, 10% used [0x000000072ffc0000, 0x00000007311fc908, 0x000000073aa60000)

to space 174720K, 0% used [0x000000073aa60000, 0x000000073aa60000, 0x0000000745500000)

concurrent mark-sweep generation reserved 2796544K, committed 2796544K, used 0K [0x0000000745500000, 0x00000007f0000000, 0x00000007f0000000)

Metaspace used 52168K, capacity 55368K, committed 55512K, reserved 311296K

class space used 5673K, capacity 6643K, committed 6720K, reserved 262144K

java.lang.RuntimeException: Unable to look up data source jdbc/SAP/BC_NZDM

I tried to update the kernel files after the error but it doesnt help. The data source jdbc/SAP/BC_NZDM cannot be found.

I changed this file etc/hosts, uncommented IPv6 but it didnt help too ( I uncommented everything up to 127.0.0.1).

# IP-Address Full-Qualified-Hostname Short-Hostname

#

127.0.0.1 localhost

# special IPv6 addresses

#::1 localhost ipv6-localhost ipv6-loopback

#fe00::0 ipv6-localnet

#ff00::0 ipv6-mcastprefix

#ff02::1 ipv6-allnodes

#ff02::2 ipv6-allrouters

#ff02::3 ipv6-allhosts

michael_swistowski · ‎2016 Jul 11

Dear Emoke,

I created a list of components, start the deploying and get mutex error. No idea what the problem is...I tried to start with user root and [SID]ADM but no difference.

SHELL:

./batchdeployer.sh comp_list.txt

INFO: Loading tool launcher...

INFO: [OS: Linux] [VM vendor: SAP AG] [VM version: 1.8.0_66] [VM type: SAP Java Server VM]

INFO: Main class to start: "com.sap.engine.extramile.BatchDeployer"

INFO: Loading 22 JAR files: [/usr/sap/MII/SYS/global/security/lib/tools/iaik_ssl.jar, /usr/sap/MII/SYS/global/security/lib/tools/iaik_jsse.jar, /usr/sap/MII/SYS/global/security/lib/tools/iaik_jce.jar, /usr/sap/MII/SYS/global/security/lib/tools/w3c_http.jar, /usr/sap/MII/SYS/global/security/lib/tools/iaik_smime.jar, ./bootstrap/sap.com~tc~je~datacollector.jar, ./bootstrap/sap.com~tc~je~cfs~container~standalone_impl.jar, ./bootstrap/sap.com~tc~bl~opensql~implStandalone.jar, ./bootstrap/sap.com~tc~bl~offline_launcher~impl.jar, ./bootstrap/sap.com~tc~exception~impl.jar, ./bootstrap/sap.com~tc~bl~deploy~offline~impl.jar, ./bootstrap/sap.com~tc~bl~iqlib~impl.jar, ./bootstrap/sap.com~tc~bl~jdsr~jdsr.jar, ./bootstrap/sap.com~tc~je~extramile.jar, ./bootstrap/sap.com~tc~bl~config~impl.jar, ./bootstrap/sap.com~tc~je~bootstrap~impl.jar, ./bootstrap/sap.com~tc~logging~java~impl.jar, ./bootstrap/jperflib.jar, ./bootstrap/sap.com~tc~sec~secstorefs~java~core.jar, ./bootstrap/sap.com~tc~je~offlineconfiguration~impl.jar, ./bootstrap/jdbc.jar, /sapdb/clients/MII/runtime/jar/sapdbc.jar]

INFO: Start

Jul 11, 2016 3:53:54 PM [Error] Incorrect input parameters! Process exited with code 4.

Usage: BatchDeployer ((-list {deployListFile_UTF-8} [-rd {componentsRootDir}])|(-sdu {sduFile} {sduFile}*)) (-subst_vars {substVarsFile})

\nNOTE: This output is taken from log file [/usr/sap/MII/J00/j2ee/cluster/bootstrap/scripts/../../../../work/jvm_recoverer.out]

\nERROR: JStart exit code [1], see details in log file [/usr/sap/MII/J00/j2ee/cluster/bootstrap/scripts/dev_recoverer]

DEV_RECOVERER

---------------------------------------------------

trc file: "dev_recoverer", trc level: 1, release: "745"

---------------------------------------------------

sysno 00

sid MII

systemid 390 (AMD/Intel x86_64 with Linux)

relno 7450

patchlevel 0

patchno 100

intno 20151301

make multithreaded, Unicode, 64 bit, optimized

profile /usr/sap/MII/SYS/profile/MII_J00_sap-netweaver

pid 12260

*

* ACTIVE TRACE LEVEL 1

* ACTIVE TRACE COMPONENTS All, egi

*

Mon Jul 11 15:53:50 2016

*

* trace logging activated, max size = 52428800 bytes, 2 versions

*

arguments :

arg[ 0] : /usr/sap/MII/J00/j2ee/cluster/bootstrap/scripts/../../../../exe/jstart

arg[ 1] : -launch

arg[ 2] : -nodename=recoverer

arg[ 3] : -file=/usr/sap/MII/J00/j2ee/cluster/bootstrap/scripts/recoverer.jstart

arg[ 4] : pf=/usr/sap/MII/SYS/profile/MII_J00_sap-netweaver

arg[ 5] : comp_list.txt

M

M [Thr 140166646245216] Mon Jul 11 15:53:51 2016

M [Thr 140166646245216] ***LOG Q0I=> NiBufIConnect: connection pending after 500ms: connect (115: Operation now in progress) [/bas/745_REL/src/base/ni/nibuf.cpp 4587]

M [Thr 140166646245216] *** ERROR => NiBufIConnect: non-buffered connect pending after 500ms (hdl 1;xxx:80) [nibuf.cpp 4598]

F [Thr 140166646245216] *** ERROR => Could not establish connection xxxx 80 NiRc=-10 [virtinfoxx.c 144]

F ********************************************************************************

F Java environment properties (/usr/sap/MII/J00/work/recoverer.jvm)

F root directory : /usr/sap/MII/J00/exe/sapjvm_8

F vendor : SAP AG

F version : 1.8.0_66

F cpu : amd64

F java vm type : server

F java vm version : 8.1.013 25.51-b02

F jvm library name : libjvm.so

F library path : /usr/sap/MII/J00/exe/sapjvm_8/jre/lib/amd64/server:/usr/sap/MII/J00/exe/sapjvm_8/jre/lib/amd64

F executable path : /usr/sap/MII/J00/exe/sapjvm_8/bin

F ********************************************************************************

F SAP extensions : available

F ********************************************************************************

M [Thr 140166646245216] NiInit3: NI already initialized; param 'maxHandles' ignored (init=1;par=65536;cur=2048)

F ********************************************************************************

F Java process [recoverer] properties:

F section name : recoverer

F config file : /usr/sap/MII/J00/j2ee/cluster/bootstrap/scripts/recoverer.jstart

F node name : recoverer

F home directory : /usr/sap/MII/J00/j2ee/cluster

F shutdown timeout : 136000 ms

F exit timeout : 7000 ms

F debuggable : false

F debugger active : false

F ********************************************************************************

F Starting process: /usr/sap/MII/J00/j2ee/cluster/bootstrap/scripts/../../../../exe/jstart

F arg[ 1] = -connId=9

F arg[ 2] = pf=/usr/sap/MII/SYS/profile/MII_J00_sap-netweaver

F arg[ 3] = -hostvm

F arg[ 4] = -nodeName=recoverer

F arg[ 5] = -file=/usr/sap/MII/J00/j2ee/cluster/bootstrap/scripts/recoverer.jstart

F arg[ 6] = -jvmFile=/usr/sap/MII/J00/work/recoverer.jvm

F arg[ 7] = -traceFile=/usr/sap/MII/J00/work/dev_recoverer.cld

F arg[ 8] = -javaOutFile=/usr/sap/MII/J00/work/jvm_recoverer.out

F arg[ 9] = comp_list.txt

F exePath : PATH=/usr/sap/MII/J00/exe/sapjvm_8/bin:/sbin:/usr/sbin:/usr/local/sbin:/root/bin:/usr/local/bin:/usr/bin:/bin:/usr/bin/X11:/usr/X11R6/bin:/usr/games:/usr/lib/mit/bin:/usr/lib/mit/sbin

F libPath : LD_LIBRARY_PATH=/usr/sap/MII/J00/exe/sapjvm_8/jre/lib/amd64/server:/usr/sap/MII/J00/exe/sapjvm_8/jre/lib/amd64:/usr/sap/MII/J00/j2ee/os_libs:/usr/sap/MII/J00/exe

F stdout : /usr/sap/MII/J00/work/std_recoverer.out

F stderr : /usr/sap/MII/J00/work/std_recoverer.out

F console : no

F debugger: no

F nice : no

F ********************************************************************************

F Process recoverer started with pid 12261

F ********************************************************************************

F [Thr 140166646245216] *** LOG => Process recoverer started (pid 12261).

F

F [Thr 140166646245216] Mon Jul 11 15:53:54 2016

F [Thr 140166646245216] *** LOG => Process recoverer stopping (pid 12261).

F [Thr 140166646245216] *** LOG => Signal 17 SIGCHLD.

F [Thr 140166646245216] *** LOG => Process recoverer stopped (pid 12261).

F

F ********************************************************************************

F *** ERROR => Mutex initialization failed.

F ***

F *** Please see section 'Launcher initialization issues'

F *** in SAP Note 1316652 for additional information and trouble shooting advice.

F ********************************************************************************

F

F [Thr 140166646245216] *** LOG => exiting (exitcode 4, retcode 1).

By Category

Related Content

Activity Groups

Industry Groups

Influence and Feedback Groups

Interest Groups

Location Groups

Customer Only Groups

Forums

Related Resources

Products

Learning and Support

About

My Account

My Account

SAP Netweaver Java 7.5 installation Java engine not start

Know the answer?

Need more details?