Unable to Start IS

KANNI_HARI · June 20, 2016, 7:31am

Hi,
I am new to Administration. Installed 9.9 and not able to start IS.
Please help me resolve the Issue.

STATUS | wrapper | 2016/06/20 10:49:20 | Base configuration file is C:\SoftwareAG\profiles\IS_default\configuration\wrapper.conf
STATUS | wrapper | 2016/06/20 10:49:20 | Found #include file in C:\SoftwareAG\profiles\IS_default\configuration\wrapper.conf: C:\SoftwareAG\profiles\IS_default/configuration/wrapper-license.conf
STATUS | wrapper | 2016/06/20 10:49:20 | Reading included configuration file, C:\SoftwareAG\profiles\IS_default\configuration\wrapper-license.conf
STATUS | wrapper | 2016/06/20 10:49:20 | Found #include file in C:\SoftwareAG\profiles\IS_default\configuration\wrapper.conf: C:\SoftwareAG\profiles\IS_default/configuration/custom_wrapper.conf
STATUS | wrapper | 2016/06/20 10:49:20 | Reading included configuration file, C:\SoftwareAG\profiles\IS_default\configuration\custom_wrapper.conf
STATUS | wrapper | 2016/06/20 10:49:20 | → Wrapper Started as Console
STATUS | wrapper | 2016/06/20 10:49:20 | Java Service Wrapper Standard Edition 64-bit 3.5.25
STATUS | wrapper | 2016/06/20 10:49:20 | Copyright (C) 1999-2014 Tanuki Software, Ltd. All Rights Reserved.
STATUS | wrapper | 2016/06/20 10:49:20 | http://wrapper.tanukisoftware.com
STATUS | wrapper | 2016/06/20 10:49:20 | Licensed to Software AG for Software AG Integration Server
STATUS | wrapper | 2016/06/20 10:49:20 |
STATUS | wrapper | 2016/06/20 10:49:20 | Launching a JVM…
INFO | jvm 1 | 2016/06/20 10:49:20 | WrapperManager: Initializing…
INFO | jvm 1 | 2016/06/20 10:49:21 | Debug options:
INFO | jvm 1 | 2016/06/20 10:49:21 | file:/C:/SoftwareAG/profiles/IS_default/bin/.options not found
INFO | jvm 1 | 2016/06/20 10:49:21 | Registering the mirror bundle management hooks.
INFO | jvm 1 | 2016/06/20 10:49:22 | Jun 20, 2016 10:49:22 AM com.softwareag.platform.console.internal.LocalConsoleManager open
INFO | jvm 1 | 2016/06/20 10:49:22 | INFO: Local JLine console flag: com.softwareag.platform.console.local.enabled=auto
INFO | jvm 1 | 2016/06/20 10:49:22 | Jun 20, 2016 10:49:22 AM com.softwareag.platform.console.internal.LocalConsoleManager open
INFO | jvm 1 | 2016/06/20 10:49:22 | INFO: Local JLine console disabled: JVM not attached to a terminal.
INFO | jvm 1 | 2016/06/20 10:49:22 | Jun 20, 2016 10:49:22 AM com.softwareag.platform.shutdown.internal.Activator start
INFO | jvm 1 | 2016/06/20 10:49:22 | INFO: Shutdown port listener disabled: Required property “com.softwareag.platform.shutdown.port” is not set or empty.
INFO | jvm 1 | 2016/06/20 10:49:22 | Jun 20, 2016 10:49:22 AM com.softwareag.platform.startup.internal.Activator$1 addingService
INFO | jvm 1 | 2016/06/20 10:49:22 | INFO: Discovered location: osgi.user.area=file:/C:/Users/Hari/user/
INFO | jvm 1 | 2016/06/20 10:49:22 | Jun 20, 2016 10:49:22 AM com.softwareag.platform.startup.internal.Activator$1 addingService
INFO | jvm 1 | 2016/06/20 10:49:22 | INFO: Discovered location: osgi.instance.area=file:/C:/SoftwareAG/profiles/IS_default/workspace/
INFO | jvm 1 | 2016/06/20 10:49:22 | Jun 20, 2016 10:49:22 AM com.softwareag.platform.startup.internal.Activator$1 addingService
INFO | jvm 1 | 2016/06/20 10:49:22 | INFO: Discovered location: osgi.configuration.area=file:/C:/SoftwareAG/profiles/IS_default/configuration/
INFO | jvm 1 | 2016/06/20 10:49:22 | Jun 20, 2016 10:49:22 AM com.softwareag.platform.startup.internal.Activator$1 addingService
INFO | jvm 1 | 2016/06/20 10:49:22 | INFO: Discovered location: osgi.install.area=file:/C:/SoftwareAG/profiles/IS_default/
INFO | jvm 1 | 2016/06/20 10:49:22 | Jun 20, 2016 10:49:22 AM com.softwareag.platform.startup.internal.Activator$1 addingService
INFO | jvm 1 | 2016/06/20 10:49:22 | INFO: Discovered location: eclipse.home.location=file:/C:/SoftwareAG/profiles/IS_default/
INFO | jvm 1 | 2016/06/20 10:49:22 | Jun 20, 2016 10:49:22 AM com.softwareag.platform.startup.internal.Activator replaceSystemPropertyLocations
INFO | jvm 1 | 2016/06/20 10:49:22 | INFO: Replacing system property sag.install.area->@url:osgi.install.area/…/… with sag.install.area->file:/C:/SoftwareAG/
INFO | jvm 1 | 2016/06/20 10:49:22 | Jun 20, 2016 10:49:22 AM com.softwareag.platform.startup.internal.Activator$1 addingService
INFO | jvm 1 | 2016/06/20 10:49:22 | INFO: Discovered location: sag.install.area=file:/C:/SoftwareAG/
INFO | jvm 1 | 2016/06/20 10:49:22 | Jun 20, 2016 10:49:22 AM com.softwareag.platform.startup.internal.LocationRegistry open
INFO | jvm 1 | 2016/06/20 10:49:22 | INFO: Registered location sag.install.area=file:/C:/SoftwareAG/
INFO | jvm 1 | 2016/06/20 10:49:23 | Jun 20, 2016 10:49:22 AM com.softwareag.platform.logging.init.internal.Activator start
INFO | jvm 1 | 2016/06/20 10:49:23 | INFO: Setting configuration directory to C:\SoftwareAG\profiles\IS_default/configuration
INFO | jvm 1 | 2016/06/20 10:49:23 | Jun 20, 2016 10:49:22 AM com.webMethods.sc.config.ConfigurationLogger log
INFO | jvm 1 | 2016/06/20 10:49:23 | INFO: Out-of-band file changes will be monitored every ‘60000’ milliseconds
INFO | jvm 1 | 2016/06/20 10:49:23 | Jun 20, 2016 10:49:22 AM com.softwareag.platform.logging.init.internal.Activator start
INFO | jvm 1 | 2016/06/20 10:49:23 | INFO: Initializing Jorunal Logger with the greedy JUL bridge
INFO | jvm 1 | 2016/06/20 10:49:24 | !SESSION 2016-06-20 10:49:20.919 -----------------------------------------------
INFO | jvm 1 | 2016/06/20 10:49:24 | eclipse.buildId=unknown
INFO | jvm 1 | 2016/06/20 10:49:24 | java.version=1.8.0_51
INFO | jvm 1 | 2016/06/20 10:49:24 | java.vendor=Oracle Corporation
INFO | jvm 1 | 2016/06/20 10:49:24 | BootLoader constants: OS=win32, ARCH=x86_64, WS=win32, NL=en_IN
INFO | jvm 1 | 2016/06/20 10:49:24 | Framework arguments: -service sagis99_2.default
INFO | jvm 1 | 2016/06/20 10:49:24 | Command-line arguments: -service sagis99_2.default
INFO | jvm 1 | 2016/06/20 10:49:24 |
INFO | jvm 1 | 2016/06/20 10:49:24 | !ENTRY com.softwareag.platform.hook 0 0 2016-06-20 10:49:24.373
INFO | jvm 1 | 2016/06/20 10:49:24 | !MESSAGE Overriding ClassLoader of com.softwareag.osgi.is.proxy_9.9.0.0000-0102 [70] with tracker (service.pid=com.softwareag.osgi.is.classloader)
INFO | jvm 1 | 2016/06/20 10:49:25 |
INFO | jvm 1 | 2016/06/20 10:49:25 | !ENTRY org.eclipse.equinox.cm 4 0 2016-06-20 10:49:25.235
INFO | jvm 1 | 2016/06/20 10:49:25 | !MESSAGE directory : Failed to get canonical path from: @path:osgi.configuration.area/event/routing/bundles
INFO | jvm 1 | 2016/06/20 10:49:25 | !STACK 0
INFO | jvm 1 | 2016/06/20 10:49:25 | java.io.IOException: The filename, directory name, or volume label syntax is incorrect
INFO | jvm 1 | 2016/06/20 10:49:25 | at java.io.WinNTFileSystem.canonicalize0(Native Method)
INFO | jvm 1 | 2016/06/20 10:49:25 | at java.io.WinNTFileSystem.canonicalize(WinNTFileSystem.java:428)
INFO | jvm 1 | 2016/06/20 10:49:25 | at java.io.File.getCanonicalPath(File.java:618)
INFO | jvm 1 | 2016/06/20 10:49:25 | at java.io.File.getCanonicalFile(File.java:643)
INFO | jvm 1 | 2016/06/20 10:49:25 | at com.softwareag.platform.bundle.watchdog.internal.Utils.readFile(Utils.java:71)
INFO | jvm 1 | 2016/06/20 10:49:25 | at com.softwareag.platform.bundle.watchdog.internal.BundleWatchdogsLifecycle$BundleWatchdogScope.(BundleWatchdogsLifecycle.java:149)
INFO | jvm 1 | 2016/06/20 10:49:25 | at com.softwareag.platform.bundle.watchdog.internal.BundleWatchdogsLifecycle.updated(BundleWatchdogsLifecycle.java:92)
INFO | jvm 1 | 2016/06/20 10:49:25 | at org.eclipse.equinox.internal.cm.ManagedServiceFactoryTracker$2.run(ManagedServiceFactoryTracker.java:190)
INFO | jvm 1 | 2016/06/20 10:49:25 | at org.eclipse.equinox.internal.cm.SerializedTaskQueue$1.run(SerializedTaskQueue.java:36)
INFO | jvm 1 | 2016/06/20 10:49:26 |
INFO | jvm 1 | 2016/06/20 10:49:26 | !ENTRY org.eclipse.equinox.ds 4 0 2016-06-20 10:49:26.865
INFO | jvm 1 | 2016/06/20 10:49:26 | !MESSAGE Could not bind a reference of component com.softwareag.eda.nerv.admin.impl.NervRouteBundleFactoryImpl. The reference is: Reference[name = TextEncrypterService, interface = com.softwareag.event.routing.encryption.NervTextEncryptor, policy = dynamic, cardinality = 0…1, target = null, bind = setTextEncryptor, unbind = unsetTextEncryptor]
INFO | jvm 1 | 2016/06/20 10:49:26 |
INFO | jvm 1 | 2016/06/20 10:49:26 | !ENTRY org.eclipse.equinox.ds 4 0 2016-06-20 10:49:26.866
INFO | jvm 1 | 2016/06/20 10:49:26 | !MESSAGE Could not bind a reference of component com.softwareag.eda.nerv.admin.impl.NervRouteBundleFactoryImpl. The reference is: Reference[name = TextEncrypterService, interface = com.softwareag.event.routing.encryption.NervTextEncryptor, policy = dynamic, cardinality = 0…1, target = null, bind = setTextEncryptor, unbind = unsetTextEncryptor]
INFO | jvm 1 | 2016/06/20 10:49:37 | SLF4J: Class path contains multiple SLF4J bindings.
INFO | jvm 1 | 2016/06/20 10:49:37 | SLF4J: Found binding in [jar:file:C:/SoftwareAG/common/lib/ext/slf4j-log4j.jar!/org/slf4j/impl/StaticLoggerBinder.class]
INFO | jvm 1 | 2016/06/20 10:49:37 | SLF4J: Found binding in [jar:file:C:/SoftwareAG/WS-Stack/lib/shared/slf4j-log4j12-1.7.7.jar!/org/slf4j/impl/StaticLoggerBinder.class]
INFO | jvm 1 | 2016/06/20 10:49:37 | SLF4J: Found binding in [jar:file:C:/SoftwareAG/WS-Stack/lib/shared/slf4j-jdk14-1.7.7.jar!/org/slf4j/impl/StaticLoggerBinder.class]
INFO | jvm 1 | 2016/06/20 10:49:37 | SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
INFO | jvm 1 | 2016/06/20 10:49:37 | SLF4J: Actual binding is of type [org.slf4j.impl.Log4jLoggerFactory]
INFO | jvm 1 | 2016/06/20 10:49:37 | No value provided for [com.webMethods.sc.mws.db.cleanup.period]. Default value [10000] is used.
INFO | jvm 1 | 2016/06/20 10:49:37 | No value provided for [com.webMethods.sc.mws.db.max.waittime]. Default value [90000] is used.
INFO | jvm 1 | 2016/06/20 10:49:38 | Initializing component: com.webMethods.rtl.util.ObjectUtil
INFO | jvm 1 | 2016/06/20 10:49:38 | Initializing component: com.webMethods.rtl.util.ThreadUtil
INFO | jvm 1 | 2016/06/20 10:49:38 | Initializing component: com.webMethods.rtl.util.FileUtil
INFO | jvm 1 | 2016/06/20 10:49:38 | Initializing component: com.webMethods.rtl.util.SystemPaths
INFO | jvm 1 | 2016/06/20 10:49:38 | Initializing component: com.webMethods.rtl.util.Debug
INFO | jvm 1 | 2016/06/20 10:49:38 | Shutting down Component: systempaths
INFO | jvm 1 | 2016/06/20 10:49:38 | Shutting down Component: fileutil
INFO | jvm 1 | 2016/06/20 10:49:38 | Shutting down Component: threadutil
INFO | jvm 1 | 2016/06/20 10:49:38 | Shutting down Component: objectutil
INFO | jvm 1 | 2016/06/20 10:49:38 | Shutdown complete.

Senthilkumar_G · June 20, 2016, 8:23am

Hari,
How did you start your IS? Were you using the startup script under /profiles/IS_default/bin or you have it running as Windows service and you start from there? Did you start with nohup?

-Senthil

KANNI_HARI · June 20, 2016, 8:39am

SenthilKumar,
I am starting as windows service. No I haven’t start with nohup will try .
Thanks.

Holger_von_Thomsen · June 20, 2016, 12:09pm

Hi Hari,

can you share the content of server.log for the startup sequence?
This might contain further informations.

Regards,
Holger

Naidu_Karri · June 28, 2016, 7:33am

Hi SMEs,

I’m facing the similar error, while trying to bring up the 9.10 IS server. Can you please help me out?

Trying to start up the server using the Window service (C:\SoftwareAG\profiles\IS_default\bin\startup.bat)

Notable error from the below logs is -
C:/SoftwareAG/profiles/IS_default/bin/.options not found

Is this file needs to be copied from somewhere? I see .options file under C:\SoftwareAG\profiles\IS_default[b]configuration [/b]but not in the bin directory.

Must appreciate your time. Thank you.

/Naidu

wrapper  | Base configuration file is C:\SoftwareAG\profiles\IS_default\configur
ation\wrapper.conf
wrapper  | Found #include file in C:\SoftwareAG\profiles\IS_default\configuratio
n\wrapper.conf: C:\SoftwareAG\profiles\IS_default/configuration/wrapper-license.
conf
wrapper  |   Reading included configuration file, C:\SoftwareAG\profiles\IS_defa
ult\configuration\wrapper-license.conf
wrapper  | Found #include file in C:\SoftwareAG\profiles\IS_default\configuratio
n\wrapper.conf: C:\SoftwareAG\profiles\IS_default/configuration/custom_wrapper.c
onf
wrapper  |   Reading included configuration file, C:\SoftwareAG\profiles\IS_defa
ult\configuration\custom_wrapper.conf
wrapper  | --> Wrapper Started as Console
wrapper  | Java Service Wrapper Standard Edition 64-bit 3.5.25
wrapper  |   Copyright (C) 1999-2014 Tanuki Software, Ltd. All Rights Reserved.
wrapper  |     http://wrapper.tanukisoftware.com
wrapper  |   Licensed to Software AG USA, Inc. for Software AG Integration Serve
r
wrapper  |
wrapper  | Launching a JVM...
jvm 1    | WrapperManager: Initializing...
[b]jvm 1    | Debug options:
jvm 1    |     file:/C:/SoftwareAG/profiles/IS_default/bin/.options not found[/b]
jvm 1    | !SESSION 2016-06-28 14:48:11.969 ------------------------------------
-----------
jvm 1    | eclipse.buildId=unknown
jvm 1    | [b]java.version=1.8.0_71[/b]
jvm 1    | java.vendor=Oracle Corporation
jvm 1    | BootLoader constants: OS=win32, ARCH=x86_64, WS=win32, NL=en_US
jvm 1    |
jvm 1    | !ENTRY com.softwareag.platform.hook.locations 1 0 2016-06-28 14:48:12
.611
jvm 1    | !MESSAGE Registering the location resolver hooks.
jvm 1    |
jvm 1    | !ENTRY com.softwareag.platform.hook.mirrors 1 0 2016-06-28 14:48:12.6
26
jvm 1    | !MESSAGE Registering the mirror bundle management hooks.
jvm 1    |
jvm 1    | !ENTRY com.softwareag.platform.hook.shutdown 32 0 2016-06-28 14:48:12
.642
jvm 1    | !MESSAGE Registering the shutdown hook.
jvm 1    |
jvm 1    | !ENTRY com.softwareag.platform.hook.locations 1 0 2016-06-28 14:48:13
.750
jvm 1    | !MESSAGE Discovered location: osgi.user.area=file:/C:/Users/akarri3/u
ser/
jvm 1    |
jvm 1    | !ENTRY com.softwareag.platform.hook.locations 1 0 2016-06-28 14:48:13
.765
jvm 1    | !MESSAGE Discovered location: osgi.instance.area=file:/C:/SoftwareAG/
profiles/IS_default/workspace/
jvm 1    |
jvm 1    | !ENTRY com.softwareag.platform.hook.locations 1 0 2016-06-28 14:48:13
.765
jvm 1    | !MESSAGE Discovered location: osgi.configuration.area=file:/C:/Softwa
reAG/profiles/IS_default/configuration/
jvm 1    |
jvm 1    | !ENTRY com.softwareag.platform.hook.locations 1 0 2016-06-28 14:48:13
.765
jvm 1    | !MESSAGE Discovered location: osgi.install.area=file:/C:/SoftwareAG/p
rofiles/IS_default/
jvm 1    |
jvm 1    | !ENTRY com.softwareag.platform.hook.locations 1 0 2016-06-28 14:48:13
.765
jvm 1    | !MESSAGE Discovered location: eclipse.home.location=file:/C:/Software
AG/profiles/IS_default/
jvm 1    |
jvm 1    | !ENTRY com.softwareag.platform.hook.locations 1 0 2016-06-28 14:48:13
.765
jvm 1    | !MESSAGE Replacing system property catalina.base->@path:osgi.configur
ation.area/tomcat with catalina.base->C:\SoftwareAG\profiles\IS_default\configur
ation\tomcat
jvm 1    |
jvm 1    | !ENTRY com.softwareag.platform.hook.locations 1 0 2016-06-28 14:48:13
.781
jvm 1    | !MESSAGE Replacing system property tomcat.home->@path:osgi.configurat
ion.area/tomcat with tomcat.home->C:\SoftwareAG\profiles\IS_default\configuratio
n\tomcat
jvm 1    |
jvm 1    | !ENTRY com.softwareag.platform.hook.locations 1 0 2016-06-28 14:48:13
.781
jvm 1    | !MESSAGE Replacing system property sag.install.area->@url:osgi.instal
l.area/../.. with sag.install.area->file:/C:/SoftwareAG/
jvm 1    |
jvm 1    | !ENTRY com.softwareag.platform.hook.locations 1 0 2016-06-28 14:48:13
.781
jvm 1    | !MESSAGE Replacing system property org.eclipse.gemini.web.tomcat.conf
ig.path->@path:osgi.configuration.area/tomcat/conf/server.xml with org.eclipse.g
emini.web.tomcat.config.path->C:\SoftwareAG\profiles\IS_default\configuration\to
mcat\conf\server.xml
jvm 1    |
jvm 1    | !ENTRY com.softwareag.platform.hook.locations 1 0 2016-06-28 14:48:13
.781
jvm 1    | !MESSAGE Discovered location: sag.install.area=file:/C:/SoftwareAG/
jvm 1    |
jvm 1    | !ENTRY com.softwareag.platform.hook.locations 1 0 2016-06-28 14:48:13
.781
jvm 1    | !MESSAGE Registered location sag.install.area=file:/C:/SoftwareAG/
jvm 1    | [Tue Jun 28 14:48:13 AEST 2016] Bootstrap: Initialized shutdown handl
er to com.softwareag.platform.hook.shutdown.HookConfiguratorImpl$$Lambda$1/38173
1278@6238651c
jvm 1    |
jvm 1    | !ENTRY com.softwareag.platform.hook.shutdown 32 0 2016-06-28 14:48:13
.890
jvm 1    | !MESSAGE Initialized graceful shutdown handler.
jvm 1    | Jun 28, 2016 2:48:14 PM com.softwareag.platform.console.internal.Loca
lConsoleManager open
jvm 1    | INFO: Local JLine console flag: com.softwareag.platform.console.local
.enabled=auto
jvm 1    | Jun 28, 2016 2:48:14 PM com.softwareag.platform.console.internal.Loca
lConsoleManager open
jvm 1    | INFO: Local JLine console disabled: JVM not attached to a terminal.
jvm 1    | Jun 28, 2016 2:48:14 PM com.softwareag.platform.logging.init.internal
.Activator start
jvm 1    | INFO: Setting configuration directory to C:\SoftwareAG\profiles\IS_de
fault/configuration
jvm 1    | Jun 28, 2016 2:48:14 PM com.webmethods.sc.config.ConfigurationLogger
log
jvm 1    | INFO: Out-of-band file changes will be monitored every '60000' millis
econds
jvm 1    | Jun 28, 2016 2:48:14 PM com.softwareag.platform.logging.init.internal
.Activator start
jvm 1    | INFO: Initializing Journal Logger with the greedy JUL bridge
jvm 1    |
jvm 1    | !ENTRY com.softwareag.platform.hook.mirrors 1 0 2016-06-28 14:48:15.5
75
jvm 1    | !MESSAGE Overriding ClassLoader of com.softwareag.osgi.is.proxy_9.10.
0.0000-0106 with tracker (service.pid=com.softwareag.osgi.is.classloader)
jvm 1    |
jvm 1    | !ENTRY com.softwareag.ext.org.eclipse.equinox.compendium.cm 4 0 2016-
06-28 14:48:17.622
jvm 1    | !MESSAGE directory : Failed to get canonical path from: @path:osgi.in
stance.area/app-platform/deployer/bundles
jvm 1    | !STACK 0
jvm 1    | java.io.IOException: The filename, directory name, or volume label sy
ntax is incorrect
jvm 1    |      at java.io.WinNTFileSystem.canonicalize0(Native Method)
jvm 1    |      at java.io.WinNTFileSystem.canonicalize(WinNTFileSystem.java:428
)
jvm 1    |      at java.io.File.getCanonicalPath(File.java:618)
jvm 1    |      at java.io.File.getCanonicalFile(File.java:643)
jvm 1    |      at com.softwareag.platform.bundle.watchdog.internal.Utils.readFi
le(Utils.java:68)
jvm 1    |      at com.softwareag.platform.bundle.watchdog.internal.BundleWatchd
ogsLifecycle$BundleWatchdogScope.<init>(BundleWatchdogsLifecycle.java:172)
jvm 1    |      at com.softwareag.platform.bundle.watchdog.internal.BundleWatchd
ogsLifecycle.updated(BundleWatchdogsLifecycle.java:94)
jvm 1    |      at org.eclipse.equinox.internal.cm.ManagedServiceFactoryTracker$
2.run(ManagedServiceFactoryTracker.java:279)
jvm 1    |      at org.eclipse.equinox.internal.cm.SerializedTaskQueue$1.run(Ser
ializedTaskQueue.java:36)
jvm 1    |
jvm 1    | !ENTRY com.softwareag.ext.org.eclipse.equinox.compendium.cm 4 0 2016-
06-28 14:48:17.638
jvm 1    | !MESSAGE directory : Failed to get canonical path from: @path:osgi.in
stance.area/app-platform/deployer/bundles
jvm 1    | !STACK 0
jvm 1    | java.io.IOException: The filename, directory name, or volume label sy
ntax is incorrect
jvm 1    |      at java.io.WinNTFileSystem.canonicalize0(Native Method)
jvm 1    |      at java.io.WinNTFileSystem.canonicalize(WinNTFileSystem.java:428
)
jvm 1    |      at java.io.File.getCanonicalPath(File.java:618)
jvm 1    |      at java.io.File.getCanonicalFile(File.java:643)
jvm 1    |      at com.softwareag.platform.bundle.watchdog.internal.Utils.readFi
le(Utils.java:68)
jvm 1    |      at com.softwareag.platform.bundle.watchdog.internal.BundleWatchd
ogsLifecycle$BundleWatchdogScope.<init>(BundleWatchdogsLifecycle.java:172)
jvm 1    |      at com.softwareag.platform.bundle.watchdog.internal.BundleWatchd
ogsLifecycle.updated(BundleWatchdogsLifecycle.java:94)
wrapper  | Wrapper Process has not received any CPU time for 22 seconds.  Extend
ing timeouts.
jvm 1    |      at org.eclipse.equinox.internal.cm.ManagedServiceFactoryTracker$
2.run(ManagedServiceFactoryTracker.java:279)
jvm 1    |      at org.eclipse.equinox.internal.cm.SerializedTaskQueue$1.run(Ser
ializedTaskQueue.java:36)
jvm 1    | Registered WSS for Spring Board!
jvm 1    | SLF4J: Class path contains multiple SLF4J bindings.
jvm 1    | SLF4J: Found binding in [jar:file:C:/SoftwareAG/common/lib/ext/slf4j-
log4j.jar!/org/slf4j/impl/StaticLoggerBinder.class]
jvm 1    | SLF4J: Found binding in [jar:file:C:/SoftwareAG/WS-Stack/lib/shared/s
lf4j-log4j12-1.7.7.jar!/org/slf4j/impl/StaticLoggerBinder.class]
jvm 1    | SLF4J: Found binding in [jar:file:C:/SoftwareAG/WS-Stack/lib/shared/s
lf4j-jdk14-1.7.7.jar!/org/slf4j/impl/StaticLoggerBinder.class]
jvm 1    | SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an e
xplanation.
jvm 1    | SLF4J: Actual binding is of type [org.slf4j.impl.Log4jLoggerFactory]
jvm 1    | No value provided for [com.webmethods.sc.mws.db.cleanup.period]. Defa
ult value [10000] is used.
jvm 1    | No value provided for [com.webmethods.sc.mws.db.max.waittime]. Defaul
t value [90000] is used.
jvm 1    | Initializing component: com.webmethods.rtl.util.ObjectUtil
jvm 1    | Initializing component: com.webmethods.rtl.util.ThreadUtil
jvm 1    | Initializing component: com.webmethods.rtl.util.FileUtil
jvm 1    | Initializing component: com.webmethods.rtl.util.SystemPaths
jvm 1    | Initializing component: com.webmethods.rtl.util.Debug
jvm 1    | Shutting down Component: systempaths
jvm 1    | Shutting down Component: fileutil
jvm 1    | Shutting down Component: threadutil
jvm 1    | Shutting down Component: objectutil
jvm 1    | Shutdown complete.

Mangat_Rai · June 28, 2016, 8:00am

No, you don’t need to copy this file. even i don’t have this file in my bin folder and my server comes up fine.

check if user which is trying to start server has permission to read\write.

were you ever able to start IS?

i see other OSGI error as well

jvm 1 | !MESSAGE directory : Failed to get canonical path from: @path:osgi.in
stance.area/app-platform/deployer/bundles
jvm 1 | !STACK 0
jvm 1 | java.io.IOException: The filename, directory name, or volume label sy
ntax is incorrect

may be installation didn’t go well?

Ankit_Kumar1 · October 24, 2016, 8:08am

Hi Everyone,

I installed 9.10 and used oracle 12C DB. Now when i am starting the IS its throwing below exceptions.

Attaching the log files for your reference.

Kindly help.

Server Log:
=============================
2016-10-24 10:35:47 IST [ISS.0025.0001C] Integration Server 9.10.0.0 Build 106
2016-10-24 10:35:47 IST [ISS.0025.0006I] License Manager started
2016-10-24 10:35:48 IST [ISS.0025.0050W] The JCE Unlimited Strength Jurisdiction Policy File was not found. Please install it.
2016-10-24 10:35:48 IST [ISS.0025.0041I] FIPS mode not initialized
2016-10-24 10:35:49 IST [BAS.0123.0001I] FSData full consistency check is being performed. Db:WmRepository4\RepoV4
2016-10-24 10:35:50 IST [ISS.0025.0017I] Repository Manager started
2016-10-24 10:35:51 IST [ISS.0025.0024I] JDBC Connection Manager started
2016-10-24 10:35:53 IST [ISS.0095.0013I] Audit Logging initialized.
2016-10-24 10:35:54 IST [ISS.0033.0168C] Cluster Node Name: *******************.
2016-10-24 10:35:54 IST [CommonLib.MWS.0002I] Initializing component: com.webMethods.rtl.util.xml.XMLUtil
2016-10-24 10:35:54 IST [CommonLib.MWS.0002I] Loading phase: [phaseID, phaseName] [miePhaseTwo, miePhaseTwo]
2016-10-24 10:35:54 IST [CommonLib.MWS.0002I] Initializing components of: miePhaseTwo
2016-10-24 10:35:54 IST [CommonLib.MWS.0002I] Initializing component: com.webMethods.portal.service.sql.core.ConnectionManager
2016-10-24 10:35:54 IST [CommonLib.MWS.0002I] No registered exception delegate
2016-10-24 10:35:54 IST [CommonLib.MWS.0002I] webMethods Integration Server Shutting down…
2016-10-24 10:35:54 IST [CommonLib.MWS.0002I] StackTrace for shutdown - this is an expect info message only :
java.lang.Thread.getStackTrace(Thread.java:1552)
com.webMethods.portal.system.PortalSystem.shutdown(PortalSystem.java:959)
com.webMethods.portal.system.PortalSystem.main(PortalSystem.java:879)
com.webMethods.sc.mws.MWSLibrary.init(MWSLibrary.java:271)
com.wm.app.b2b.server.cds.CDSUserManager.initialize(CDSUserManager.java:186)
com.wm.app.b2b.server.UserManager.jndiInit(UserManager.java:589)
com.wm.app.b2b.server.UserManager.init(UserManager.java:520)
com.wm.app.b2b.server.Server.run(Server.java:438)

2016-10-24 10:35:54 IST [CommonLib.MWS.0002I] Stopped sending JDK log messages to Log4j
2016-10-24 10:35:54 IST [ISS.0024.0010C] Central User Manager initialization failed with error : [POP.004.0201] Cannot initialize MWS, invalid database schema is specified
2016-10-24 10:35:54 IST [WmAuditingSC.destination.1122E] Destination ‘ErrorDBDest’ caught unexpected Throwable logging audit event: [SoftwareAG][Oracle JDBC Driver][Oracle]ORA-00942: table or view does not exist

2016-10-24 10:35:54 IST [WmAuditingSC.logger.3405E] In logger ‘Error Logger’, failed to log to destination; reason: Fatal SQLException detected
2016-10-24 10:35:54 IST [ISS.0024.0001E] Could not initialize User Manager.
2016-10-24 10:35:54 IST [ISS.0025.0021I] ACL Manager started
2016-10-24 10:35:54 IST [ISS.0025.0008I] State Manager started
2016-10-24 10:35:54 IST [ISS.0025.0010I] Service Manager started
2016-10-24 10:35:54 IST [ISS.0025.0020I] Validation Processor started
2016-10-24 10:35:54 IST [ISS.0025.0022I] Statistics Processor started
2016-10-24 10:35:54 IST [ISS.0025.0018I] Invoke Manager started
2016-10-24 10:35:54 IST [ISS.0025.0012I] Cache Manager started
2016-10-24 10:35:55 IST [WmAuditingSC.destination.1122E] Destination ‘ErrorDBDest’ caught unexpected Throwable logging audit event: [SoftwareAG][Oracle JDBC Driver][Oracle]ORA-00942: table or view does not exist

2016-10-24 10:35:55 IST [WmAuditingSC.queue.2406E] AuditQueueWorker for queue ‘ErrorQueue’ failed to log event to destination ‘ErrorDBDest’; reason '[SoftwareAG][Oracle JDBC Driver][Oracle]ORA-00942: table or view does not exist
'; stack trace follows
2016-10-24 10:35:55 IST [WmAuditingSC.queue.2416I] In queue ‘ErrorQueue’ worker, the preceding error is non-recoverable; failing-over
2016-10-24 10:36:46 IST [ISS.0137.0006E] Scheduler: Error initializing scheduler: [SoftwareAG][Oracle JDBC Driver][Oracle]ORA-00942: table or view does not exist

2016-10-24 10:36:46 IST [WmAuditingSC.destination.1122E] Destination ‘ErrorDBDest’ caught unexpected Throwable logging audit event: [SoftwareAG][Oracle JDBC Driver][Oracle]ORA-00942: table or view does not exist

2016-10-24 10:36:46 IST [WmAuditingSC.logger.3405E] In logger ‘Error Logger’, failed to log to destination; reason: Fatal SQLException detected
2016-10-24 10:36:46 IST [ISS.0028.0012I] WmRoot: Startup service (wm.server.dispatcher:init)
2016-10-24 10:36:46 IST [ISS.0028.0012I] WmRoot: Startup service (wm.server.tx:init)
2016-10-24 10:36:46 IST [WmAuditingSC.destination.1122E] Destination ‘SessionDBDest’ caught unexpected Throwable logging audit event: [SoftwareAG][Oracle JDBC Driver][Oracle]ORA-00942: table or view does not exist

2016-10-24 10:36:46 IST [WmAuditingSC.logger.3405E] In logger ‘Session Logger’, failed to log to destination; reason: Fatal SQLException detected
2016-10-24 10:36:46 IST [WmAuditingSC.destination.1122E] Destination ‘ErrorDBDest’ caught unexpected Throwable logging audit event: [SoftwareAG][Oracle JDBC Driver][Oracle]ORA-00942: table or view does not exist

2016-10-24 10:36:46 IST [WmAuditingSC.queue.2406E] AuditQueueWorker for queue ‘ErrorQueue’ failed to log event to destination ‘ErrorDBDest’; reason '[SoftwareAG][Oracle JDBC Driver][Oracle]ORA-00942: table or view does not exist
'; stack trace follows
2016-10-24 10:36:46 IST [WmAuditingSC.queue.2416I] In queue ‘ErrorQueue’ worker, the preceding error is non-recoverable; failing-over
2016-10-24 10:36:47 IST [WmAuditingSC.destination.1122E] Destination ‘ErrorDBDest’ caught unexpected Throwable logging audit event: [SoftwareAG][Oracle JDBC Driver][Oracle]ORA-00942: table or view does not exist

2016-10-24 10:36:47 IST [WmAuditingSC.logger.3405E] In logger ‘Error Logger’, failed to log to destination; reason: Fatal SQLException detected
2016-10-24 10:36:47 IST [ISC.0063.0042I] Job Manager not initialized.
2016-10-24 10:36:47 IST [WmAuditingSC.destination.1122E] Destination ‘SessionDBDest’ caught unexpected Throwable logging audit event: [SoftwareAG][Oracle JDBC Driver][Oracle]ORA-00942: table or view does not exist

2016-10-24 10:36:47 IST [WmAuditingSC.queue.2406E] AuditQueueWorker for queue ‘SessionQueue’ failed to log event to destination ‘SessionDBDest’; reason '[SoftwareAG][Oracle JDBC Driver][Oracle]ORA-00942: table or view does not exist
'; stack trace follows
2016-10-24 10:36:47 IST [WmAuditingSC.queue.2416I] In queue ‘SessionQueue’ worker, the preceding error is non-recoverable; failing-over
2016-10-24 10:36:48 IST [WmAuditingSC.destination.1122E] Destination ‘ErrorDBDest’ caught unexpected Throwable logging audit event: [SoftwareAG][Oracle JDBC Driver][Oracle]ORA-00942: table or view does not exist

2016-10-24 10:36:48 IST [WmAuditingSC.logger.3405E] In logger ‘Error Logger’, failed to log to destination; reason: Fatal SQLException detected
2016-10-24 10:36:48 IST [WmAuditingSC.destination.1122E] Destination ‘ErrorDBDest’ caught unexpected Throwable logging audit event: [SoftwareAG][Oracle JDBC Driver][Oracle]ORA-00942: table or view does not exist

2016-10-24 10:36:48 IST [WmAuditingSC.queue.2406E] AuditQueueWorker for queue ‘ErrorQueue’ failed to log event to destination ‘ErrorDBDest’; reason '[SoftwareAG][Oracle JDBC Driver][Oracle]ORA-00942: table or view does not exist
'; stack trace follows
2016-10-24 10:36:48 IST [WmAuditingSC.queue.2416I] In queue ‘ErrorQueue’ worker, the preceding error is non-recoverable; failing-over
2016-10-24 10:36:48 IST [WmAuditingSC.destination.1122E] Destination ‘ErrorDBDest’ caught unexpected Throwable logging audit event: [SoftwareAG][Oracle JDBC Driver][Oracle]ORA-00942: table or view does not exist
====================================
sag-osgi logs:
====================================
file:/C:/SoftwareAG/IntegrationServer/instances/default/config/wss/services/EchoWS.aar
[org.eclipse.gemini.blueprint.extender.internal.dependencies.startup.DependencyWaiterApplicationContextExecutor] 2016-10-24 11:33:48,812 WARN : Timeout occurred before finding service dependencies for [OsgiBundleXmlApplicationContext(bundle=com.softwareag.platform.passman.mbean, config=bundleentry://116.fwk790066067/OSGI-INF/blueprint/blueprint.xml)]
[org.eclipse.gemini.blueprint.extender.internal.blueprint.activator.BlueprintLoaderListener] 2016-10-24 11:33:48,817 ERROR: Application context refresh failed (OsgiBundleXmlApplicationContext(bundle=com.softwareag.platform.passman.mbean, config=bundleentry://116.fwk790066067/OSGI-INF/blueprint/blueprint.xml))
org.springframework.context.ApplicationContextException: Application context initialization for ‘com.softwareag.platform.passman.mbean’ has timed out waiting for (objectClass=com.webMethods.sc.security.passman.PasswordManager)
at org.eclipse.gemini.blueprint.extender.internal.dependencies.startup.DependencyWaiterApplicationContextExecutor.timeout(DependencyWaiterApplicationContextExecutor.java:489)
at org.eclipse.gemini.blueprint.extender.internal.dependencies.startup.DependencyWaiterApplicationContextExecutor.access$000(DependencyWaiterApplicationContextExecutor.java:54)
at org.eclipse.gemini.blueprint.extender.internal.dependencies.startup.DependencyWaiterApplicationContextExecutor$WatchDogTask.run(DependencyWaiterApplicationContextExecutor.java:109)
at java.util.TimerThread.mainLoop(Timer.java:555)
at java.util.TimerThread.run(Timer.java:505)
[org.eclipse.gemini.blueprint.extender.internal.dependencies.startup.DependencyWaiterApplicationContextExecutor] 2016-10-24 11:33:48,819 ERROR: Unable to create application context for [com.softwareag.platform.passman.mbean], unsatisfied dependencies: Dependency on [(objectClass=com.webMethods.sc.security.passman.PasswordManager)] (from bean [&passmanService])
org.springframework.context.ApplicationContextException: Application context initialization for ‘com.softwareag.platform.passman.mbean’ has timed out waiting for (objectClass=com.webMethods.sc.security.passman.PasswordManager)
at org.eclipse.gemini.blueprint.extender.internal.dependencies.startup.DependencyWaiterApplicationContextExecutor.timeout(DependencyWaiterApplicationContextExecutor.java:489)
at org.eclipse.gemini.blueprint.extender.internal.dependencies.startup.DependencyWaiterApplicationContextExecutor.access$000(DependencyWaiterApplicationContextExecutor.java:54)
at org.eclipse.gemini.blueprint.extender.internal.dependencies.startup.DependencyWaiterApplicationContextExecutor$WatchDogTask.run(DependencyWaiterApplicationContextExecutor.java:109)
at java.util.TimerThread.mainLoop(Timer.java:555)
at java.util.TimerThread.run(Timer.java:505)

Holger_von_Thomsen · October 24, 2016, 10:45am

Hi Ankit,

did you create the database schema with DatabaseConfigurator before starting IS?

Regards,
Holger

Krishna_PB · October 24, 2016, 2:24pm

It seems to be you are not connecting to the database, please check whether you have created the DB schema and DB is up and running.

Regards,
Krishna

MR_as173d · October 29, 2016, 12:43pm

The error is it’s unable to locate the tables. If you don’t want to use external DB, use embedded DB which makes you to proceed.

Thanks,