[ISS.0014.0054C] Error occurred while initializing server

Hello,

i’m trying to start an Integration server in a docker container. This IS is a copy of an existing IS who work well.
When I start my container I get the error message “[ISS.0014.0054C] Error occurred while initializing server”

I’m in webMethods 10.5 on centos 7 OS.

I’ve seen a post where the error is similar but there is no solution.

here my server.log :

2020-09-02 14:27:49 UTC [ISS.0025.0001I] Integration Server 10.5.0.0 Build 84
2020-09-02 14:27:49 UTC [ISS.0025.0006I] License Manager started
2020-09-02 14:27:49 UTC [ISS.0001.0008W] Microservices Functionality disabled due to license restrictions.
2020-09-02 14:27:50 UTC [ISS.0025.0049I] The JCE Unlimited Strength Jurisdiction Policy File was found
2020-09-02 14:27:50 UTC [ISS.0025.0041I] FIPS mode not initialized
2020-09-02 14:27:52 UTC [ISS.0025.0024I] JDBC Connection Manager started
2020-09-02 14:27:56 UTC [ISS.0095.0013I] Audit Logging initialized.
2020-09-02 14:27:59 UTC [ISS.0033.0168I] Cluster Node Name: 5d6e56ec68b0.
2020-09-02 14:28:00 UTC [ISS.0025.0017I] Repository Manager started
2020-09-02 14:28:00 UTC [ISS.0025.0021I] ACL Manager started
2020-09-02 14:28:00 UTC [ISS.0024.0015E] Integration Server cannot add group "My webMethods Users" to ACL "Anonymous" because group "My webMethods Users" is not found.
2020-09-02 14:28:00 UTC [ISS.0024.0015E] Integration Server cannot add group "My webMethods Users" to ACL "Default" because group "My webMethods Users" is not found.
2020-09-02 14:28:00 UTC [ISS.0024.0015E] Integration Server cannot add group "My webMethods Administrators" to ACL "Administrators" because group "My webMethods Administrators" is not found.
2020-09-02 14:28:00 UTC [ISS.0024.0015E] Integration Server cannot add group "My webMethods Administrators" to ACL "Internal" because group "My webMethods Administrators" is not found.
2020-09-02 14:28:00 UTC [ISS.0024.0015E] Integration Server cannot add group "My webMethods Administrators" to ACL "Developers" because group "My webMethods Administrators" is not found.
2020-09-02 14:28:00 UTC [ISS.0024.0015E] Integration Server cannot add group "My webMethods Administrators" to ACL "Replicators" because group "My webMethods Administrators" is not found.
2020-09-02 14:28:00 UTC [ISS.0025.0008I] State Manager started
2020-09-02 14:28:00 UTC [ISS.0025.0010I] Service Manager started
2020-09-02 14:28:00 UTC [ISS.0025.0020I] Validation Processor started
2020-09-02 14:28:00 UTC [ISS.0025.0022I] Statistics Processor started
2020-09-02 14:28:00 UTC [ISS.0025.0018I] Invoke Manager started
2020-09-02 14:28:00 UTC [ISS.0025.0012I] Cache Manager started
2020-09-02 14:28:01 UTC [ISS.0098.0026I] Document Store DefaultStore initialized
2020-09-02 14:28:03 UTC [ISS.0098.0026I] Document Store VolatileTriggerStore initialized
2020-09-02 14:28:03 UTC [ISS.0098.0026I] Document Store TriggerStore initialized
2020-09-02 14:28:03 UTC [ISS.0098.0026I] Document Store ResubmitStore initialized
2020-09-02 14:28:06 UTC [ISS.0153.0007I] webMethods Messaging connection alias IS_UM_CONNECTION started
2020-09-02 14:28:06 UTC [ISS.0153.0007I] webMethods Messaging connection alias IS_DES_CONNECTION started
2020-09-02 14:28:06 UTC [ISS.0153.0000I] webMethods Messaging subsystem started
2020-09-02 14:28:06 UTC [ISS.0106.0001I] Join Manager initialized
2020-09-02 14:28:06 UTC [ISS.0025.0032I] Dispatcher initialized
2020-09-02 14:28:06 UTC [ISS.0134.0128I] JMS subsystem is starting.
2020-09-02 14:28:07 UTC [ISS.0134.0998I] DEFAULT_IS_JMS_CONNECTION cache enabled :: true
2020-09-02 14:28:07 UTC [ISS.0134.0998I] PE_NONTRANSACTIONAL_ALIAS cache enabled :: true
2020-09-02 14:28:07 UTC [ISS.0134.0000I] JMS subsystem started
2020-09-02 14:28:07 UTC [ISS.0173.0000I] Common Messaging subsystem started
2020-09-02 14:28:08 UTC [SCC.0121.0093I] XA Recovery Store initialized
2020-09-02 14:28:08 UTC [com.webmethods.fabric.agent.FabricAgent.null.load.startI] [agent.load.start] Loading registry config file /opt/softwareag/IntegrationServer/instances/pont/config/fabric-agent.xml
2020-09-02 14:28:08 UTC [com.webmethods.fabric.agent.FabricAgent.null.startI] [agent.start] Starting Fabric Agent
2020-09-02 14:28:08 UTC [ISS.0025.0055I] Lock Manager initialized
2020-09-02 14:28:14 UTC [ISS.0014.0054C] Error occurred while initializing server: java.lang.ExceptionInInitializerError    at org.eclipse.jetty.http.pathmap.PathMappings.<clinit>(PathMappings.java:49)       at com.wm.app.b2b.server.websocket.WebSocketServerEndpointManager.<init>(WebSocketServerEndpointManager.java:34)    at com.wm.app.b2b.server.websocket.WebSocketServerEndpointManager.<clinit>(WebSocketServerEndpointManager.java:33)      at 
2020-09-02 14:27:49 UTC [ISS.0025.0001I] Integration Server 10.5.0.0 Build 84
2020-09-02 14:27:49 UTC [ISS.0025.0006I] License Manager started
2020-09-02 14:27:49 UTC [ISS.0001.0008W] Microservices Functionality disabled due to license restrictions.
2020-09-02 14:27:50 UTC [ISS.0025.0049I] The JCE Unlimited Strength Jurisdiction Policy File was found
2020-09-02 14:27:50 UTC [ISS.0025.0041I] FIPS mode not initialized
2020-09-02 14:27:52 UTC [ISS.0025.0024I] JDBC Connection Manager started
2020-09-02 14:27:56 UTC [ISS.0095.0013I] Audit Logging initialized.
2020-09-02 14:27:59 UTC [ISS.0033.0168I] Cluster Node Name: 5d6e56ec68b0.
2020-09-02 14:28:00 UTC [ISS.0025.0017I] Repository Manager started
2020-09-02 14:28:00 UTC [ISS.0025.0021I] ACL Manager started
2020-09-02 14:28:00 UTC [ISS.0024.0015E] Integration Server cannot add group "My webMethods Users" to ACL "Anonymous" because group "My webMethods Users" is not found.
2020-09-02 14:28:00 UTC [ISS.0024.0015E] Integration Server cannot add group "My webMethods Users" to ACL "Default" because group "My webMethods Users" is not found.
2020-09-02 14:28:00 UTC [ISS.0024.0015E] Integration Server cannot add group "My webMethods Administrators" to ACL "Administrators" because group "My webMethods Administrators" is not found.
2020-09-02 14:28:00 UTC [ISS.0024.0015E] Integration Server cannot add group "My webMethods Administrators" to ACL "Internal" because group "My webMethods Administrators" is not found.
2020-09-02 14:28:00 UTC [ISS.0024.0015E] Integration Server cannot add group "My webMethods Administrators" to ACL "Developers" because group "My webMethods Administrators" is not found.
2020-09-02 14:28:00 UTC [ISS.0024.0015E] Integration Server cannot add group "My webMethods Administrators" to ACL "Replicators" because group "My webMethods Administrators" is not found.
2020-09-02 14:28:00 UTC [ISS.0025.0008I] State Manager started
2020-09-02 14:28:00 UTC [ISS.0025.0010I] Service Manager started
2020-09-02 14:28:00 UTC [ISS.0025.0020I] Validation Processor started
2020-09-02 14:28:00 UTC [ISS.0025.0022I] Statistics Processor started
2020-09-02 14:28:00 UTC [ISS.0025.0018I] Invoke Manager started
2020-09-02 14:28:00 UTC [ISS.0025.0012I] Cache Manager started
2020-09-02 14:28:01 UTC [ISS.0098.0026I] Document Store DefaultStore initialized
2020-09-02 14:28:03 UTC [ISS.0098.0026I] Document Store VolatileTriggerStore initialized
2020-09-02 14:28:03 UTC [ISS.0098.0026I] Document Store TriggerStore initialized
2020-09-02 14:28:03 UTC [ISS.0098.0026I] Document Store ResubmitStore initialized
2020-09-02 14:28:06 UTC [ISS.0153.0007I] webMethods Messaging connection alias IS_UM_CONNECTION started
2020-09-02 14:28:06 UTC [ISS.0153.0007I] webMethods Messaging connection alias IS_DES_CONNECTION started
2020-09-02 14:28:06 UTC [ISS.0153.0000I] webMethods Messaging subsystem started
2020-09-02 14:28:06 UTC [ISS.0106.0001I] Join Manager initialized
2020-09-02 14:28:06 UTC [ISS.0025.0032I] Dispatcher initialized
2020-09-02 14:28:06 UTC [ISS.0134.0128I] JMS subsystem is starting.
2020-09-02 14:28:07 UTC [ISS.0134.0998I] DEFAULT_IS_JMS_CONNECTION cache enabled :: true
2020-09-02 14:28:07 UTC [ISS.0134.0998I] PE_NONTRANSACTIONAL_ALIAS cache enabled :: true
2020-09-02 14:28:07 UTC [ISS.0134.0000I] JMS subsystem started
2020-09-02 14:28:07 UTC [ISS.0173.0000I] Common Messaging subsystem started
2020-09-02 14:28:08 UTC [SCC.0121.0093I] XA Recovery Store initialized
2020-09-02 14:28:08 UTC [com.webmethods.fabric.agent.FabricAgent.null.load.startI] [agent.load.start] Loading registry config file /opt/softwareag/IntegrationServer/instances/pont/config/fabric-agent.xml
2020-09-02 14:28:08 UTC [com.webmethods.fabric.agent.FabricAgent.null.startI] [agent.start] Starting Fabric Agent
2020-09-02 14:28:08 UTC [ISS.0025.0055I] Lock Manager initialized
2020-09-02 14:28:14 UTC [ISS.0014.0054C] Error occurred while initializing server: java.lang.ExceptionInInitializerError    at org.eclipse.jetty.http.pathmap.PathMappings.<clinit>(PathMappings.java:49)       at com.wm.app.b2b.server.websocket.WebSocketServerEndpointManager.<init>(WebSocketServerEndpointManager.java:34)    at com.wm.app.b2b.server.websocket.WebSocketServerEndpointManager.<clinit>(WebSocketServerEndpointManager.java:33)      at com.wm.app.b2b.server.Server.run(Server.java:665)

Thank you in advance

Hi,
Can you please describe steps you used to create the Docker image?
Thanks,
-Kalpesh.

Hi,
We have a git where we upload all the source code of the IS except the instances in the “instances” directory and “profiles” directory.
A Jenkins import the git project, compress the package and upload it on a nexus.
Then we launch a script on our vm. The script download the package from the nexus and execute the dockerfile who build the image from a centos 7 image who is already on the vm. This image is our base for the next step.

Then we have another git where we upload the instances and profiles.
Another Jenkins import the git project, compress the package and upload it on another nexus.
Finally the dockerfile build the image using the “base” image of the previous step. Then the ENTRYPOINT property starts the script : IntegrationServer/bin/StartContainer.sh

Hi G LR,

If the docker container itself is up and running, then login into that container and try to start the IS manually within it and see the error.

I’m not sure if some network/proxy issue is causing this problem by the last exception in the error log you shared.

Regards,
Firoz N

Hi,

when i start the IS with the "server.sh’ it’s working well, but when I use the “startcontainer.sh” it causes the error above.
But if I use the “server.sh” as the entrypoint the container stop after the end of the sript “server.sh”.

So I thinks the problem is more in the script “startcontainer.sh” but i don’t see why this specific script dosen’t work

Hi,

Are there any other errors reported in wrapper.log file?

Thanks,
-Kalpesh.

Hi,

here’s the wrapper.log files, I have checked the jar and it seems to bug on an object “logger” or “log”, maybe it’s a problem of an absolute path to a log file location.

Is there a file where an absolute path for a log file is configured ?

STATUS | wrapper  | 2020/09/09 12:27:46 | Base configuration file is /opt/softwareag/profiles/IS_pont/configuration/wrapper.conf
STATUS | wrapper  | 2020/09/09 12:27:46 | Found #include file in /opt/softwareag/profiles/IS_pont/configuration/wrapper.conf: %OSGI_INSTALL_AREA%/configuration/wrapper-license.conf
STATUS | wrapper  | 2020/09/09 12:27:46 |   After environment variable replacements: /opt/softwareag/profiles/IS_pont/configuration/wrapper-license.conf
STATUS | wrapper  | 2020/09/09 12:27:46 |   Reading included configuration file, /opt/softwareag/profiles/IS_pont/configuration/wrapper-license.conf
STATUS | wrapper  | 2020/09/09 12:27:46 | Found #include file in /opt/softwareag/profiles/IS_pont/configuration/wrapper.conf: %OSGI_INSTALL_AREA%/configuration/custom_wrapper.conf
STATUS | wrapper  | 2020/09/09 12:27:46 |   After environment variable replacements: /opt/softwareag/profiles/IS_pont/configuration/custom_wrapper.conf
STATUS | wrapper  | 2020/09/09 12:27:46 |   Reading included configuration file, /opt/softwareag/profiles/IS_pont/configuration/custom_wrapper.conf
STATUS | wrapper  | 2020/09/09 12:27:46 | --> Wrapper Started as Console
STATUS | wrapper  | 2020/09/09 12:27:46 | Java Service Wrapper Standard Edition 64-bit 3.5.37
STATUS | wrapper  | 2020/09/09 12:27:46 |   Copyright (C) 1999-2018 Tanuki Software, Ltd. All Rights Reserved.
STATUS | wrapper  | 2020/09/09 12:27:46 |     http://wrapper.tanukisoftware.com
STATUS | wrapper  | 2020/09/09 12:27:46 |   Licensed to Software AG USA, Inc. for Software AG Integration Server
STATUS | wrapper  | 2020/09/09 12:27:46 |
STATUS | wrapper  | 2020/09/09 12:27:47 | Launching a JVM...
INFO   | jvm 1    | 2020/09/09 12:27:47 | WrapperManager: Initializing...
INFO   | jvm 1    | 2020/09/09 12:27:47 | [ProxyLogManager] Taking over JUL
INFO   | jvm 1    | 2020/09/09 12:27:47 | [ProxyLogManager] readConfiguration() -> com.softwareag.platform.jul.proxy.DefaultLogManager@3a71f4dd
INFO   | jvm 1    | 2020/09/09 12:27:47 | Debug options:
INFO   | jvm 1    | 2020/09/09 12:27:47 |     file:/opt/softwareag/IntegrationServer/instances/../../profiles/IS_pont/bin/.options not found
INFO   | jvm 1    | 2020/09/09 12:27:47 | !SESSION 2020-09-09 12:27:47.823 -----------------------------------------------
INFO   | jvm 1    | 2020/09/09 12:27:47 | eclipse.buildId=unknown
INFO   | jvm 1    | 2020/09/09 12:27:47 | java.version=1.8.0_222
INFO   | jvm 1    | 2020/09/09 12:27:47 | java.vendor=Azul Systems, Inc.
INFO   | jvm 1    | 2020/09/09 12:27:47 | BootLoader constants: OS=linux, ARCH=x86_64, WS=gtk, NL=en_US
INFO   | jvm 1    | 2020/09/09 12:27:47 |
INFO   | jvm 1    | 2020/09/09 12:27:47 | !ENTRY com.softwareag.platform.hook.locations 1 0 2020-09-09 12:27:47.963
INFO   | jvm 1    | 2020/09/09 12:27:47 | !MESSAGE Registering the location resolver hooks.
INFO   | jvm 1    | 2020/09/09 12:27:47 |
INFO   | jvm 1    | 2020/09/09 12:27:47 | !ENTRY com.softwareag.platform.hook.mirrors 1 0 2020-09-09 12:27:47.967
INFO   | jvm 1    | 2020/09/09 12:27:47 | !MESSAGE Registering the mirror bundle management hooks.
INFO   | jvm 1    | 2020/09/09 12:27:48 |
INFO   | jvm 1    | 2020/09/09 12:27:48 | !ENTRY com.softwareag.platform.hook.shutdown 1 0 2020-09-09 12:27:47.978
INFO   | jvm 1    | 2020/09/09 12:27:48 | !MESSAGE Registering the shutdown hook.
INFO   | jvm 1    | 2020/09/09 12:27:48 |
INFO   | jvm 1    | 2020/09/09 12:27:48 | !ENTRY com.softwareag.platform.hook.locations 1 0 2020-09-09 12:27:48.324
INFO   | jvm 1    | 2020/09/09 12:27:48 | !MESSAGE Created java temp directory /opt/softwareag/profiles/IS_pont/workspace/temp
INFO   | jvm 1    | 2020/09/09 12:27:48 |
INFO   | jvm 1    | 2020/09/09 12:27:48 | !ENTRY com.softwareag.platform.hook.locations 1 0 2020-09-09 12:27:48.332
INFO   | jvm 1    | 2020/09/09 12:27:48 | !MESSAGE Discovered location: osgi.user.area=file:/opt/softwareag/user/
INFO   | jvm 1    | 2020/09/09 12:27:48 |
INFO   | jvm 1    | 2020/09/09 12:27:48 | !ENTRY com.softwareag.platform.hook.locations 1 0 2020-09-09 12:27:48.332
INFO   | jvm 1    | 2020/09/09 12:27:48 | !MESSAGE Discovered location: osgi.instance.area=file:/opt/softwareag/profiles/IS_pont/workspace/
INFO   | jvm 1    | 2020/09/09 12:27:48 |
INFO   | jvm 1    | 2020/09/09 12:27:48 | !ENTRY com.softwareag.platform.hook.locations 1 0 2020-09-09 12:27:48.333
INFO   | jvm 1    | 2020/09/09 12:27:48 | !MESSAGE Discovered location: osgi.configuration.area=file:/opt/softwareag/profiles/IS_pont/configuration/
INFO   | jvm 1    | 2020/09/09 12:27:48 |
INFO   | jvm 1    | 2020/09/09 12:27:48 | !ENTRY com.softwareag.platform.hook.locations 1 0 2020-09-09 12:27:48.333
INFO   | jvm 1    | 2020/09/09 12:27:48 | !MESSAGE Discovered location: osgi.install.area=file:/opt/softwareag/profiles/IS_pont/
INFO   | jvm 1    | 2020/09/09 12:27:48 |
INFO   | jvm 1    | 2020/09/09 12:27:48 | !ENTRY com.softwareag.platform.hook.locations 1 0 2020-09-09 12:27:48.333
INFO   | jvm 1    | 2020/09/09 12:27:48 | !MESSAGE Discovered location: eclipse.home.location=file:/opt/softwareag/profiles/IS_pont/
INFO   | jvm 1    | 2020/09/09 12:27:48 |
INFO   | jvm 1    | 2020/09/09 12:27:48 | !ENTRY com.softwareag.platform.hook.locations 1 0 2020-09-09 12:27:48.349
INFO   | jvm 1    | 2020/09/09 12:27:48 | !MESSAGE Replacing system property sag.install.area->@url:osgi.install.area/../.. with sag.install.area->file:/opt/softwareag/
INFO   | jvm 1    | 2020/09/09 12:27:48 |
INFO   | jvm 1    | 2020/09/09 12:27:48 | !ENTRY com.softwareag.platform.hook.locations 1 0 2020-09-09 12:27:48.354
INFO   | jvm 1    | 2020/09/09 12:27:48 | !MESSAGE Discovered location: sag.install.area=file:/opt/softwareag/
INFO   | jvm 1    | 2020/09/09 12:27:48 |
INFO   | jvm 1    | 2020/09/09 12:27:48 | !ENTRY com.softwareag.platform.hook.locations 1 0 2020-09-09 12:27:48.354
INFO   | jvm 1    | 2020/09/09 12:27:48 | !MESSAGE Registered location sag.install.area=file:/opt/softwareag/
INFO   | jvm 1    | 2020/09/09 12:27:48 | [Wed Sep 09 12:27:48 UTC 2020] Bootstrap: Initialized shutdown handler to com.softwareag.platform.hook.shutdown.HookConfiguratorImpl$$Lambda$1/887889012@73d11a4c
INFO   | jvm 1    | 2020/09/09 12:27:48 |
INFO   | jvm 1    | 2020/09/09 12:27:48 | !ENTRY com.softwareag.platform.hook.shutdown 1 0 2020-09-09 12:27:48.515
INFO   | jvm 1    | 2020/09/09 12:27:48 | !MESSAGE Initialized graceful shutdown handler.
INFO   | jvm 1    | 2020/09/09 12:27:51 | [ProxyLogManager] setDelegate: com.softwareag.platform.jul.proxy.DefaultLogManager@3a71f4dd -> org.apache.logging.log4j.jul.LogManager@9248f73
INFO   | jvm 1    | 2020/09/09 12:27:54 | SecurityConfiguration for ESAPI.Logger not found in ESAPI.properties. Using default: org.owasp.esapi.reference.JavaLogFactory
INFO   | jvm 1    | 2020/09/09 12:27:54 | SecurityConfiguration for ESAPI.Logger not found in ESAPI.properties. Using default: org.owasp.esapi.reference.JavaLogFactory
INFO   | jvm 1    | 2020/09/09 12:27:54 | SecurityConfiguration for ESAPI.Logger not found in ESAPI.properties. Using default: org.owasp.esapi.reference.JavaLogFactory
INFO   | jvm 1    | 2020/09/09 12:27:54 | SecurityConfiguration for ESAPI.Logger not found in ESAPI.properties. Using default: org.owasp.esapi.reference.JavaLogFactory
INFO   | jvm 1    | 2020/09/09 12:28:02 |
INFO   | jvm 1    | 2020/09/09 12:28:02 | !ENTRY com.softwareag.platform.hook.mirrors 1 0 2020-09-09 12:28:02.433
INFO   | jvm 1    | 2020/09/09 12:28:02 | !MESSAGE Overriding ClassLoader of com.softwareag.osgi.is.proxy_10.5.0.0000-0084 with tracker (service.pid=com.softwareag.osgi.is.classloader)
INFO   | jvm 1    | 2020/09/09 12:28:09 | INFO: Getting audit  config from it's default location.
INFO   | jvm 1    | 2020/09/09 12:28:09 | WARNING: Audit configuration neither exists, nor could be found on the classpath. Auditing will not work.
INFO   | jvm 1    | 2020/09/09 12:28:09 | INFO: Using dummy audit implementation.
INFO   | jvm 1    | 2020/09/09 12:28:10 | 2020-09-09 12:28:10 UTC [ISS.0028.9999I] Validating License file for Microservices
INFO   | jvm 1    | 2020/09/09 12:28:10 | 2020-09-09 12:28:10 UTC [ISS.0028.0103C] License file /opt/softwareag/IntegrationServer/instances/pont/config/licenseKey.xml is not enabled for the Microservices functionality.
INFO   | jvm 1    | 2020/09/09 12:28:10 | 2020-09-09 12:28:10 UTC [ISS.0028.0104C] Unable to perform configuration variables processing because license is not valid.
INFO   | jvm 1    | 2020/09/09 12:28:12 | ISSERVER|| 2020-09-09 12:28:12 UTC [ISS.0025.0001I] Integration Server 10.5.0.0 Build 84
INFO   | jvm 1    | 2020/09/09 12:28:12 | ISSERVER|| 2020-09-09 12:28:12 UTC [ISS.0025.0006I] License Manager started
INFO   | jvm 1    | 2020/09/09 12:28:12 | ISSERVER|| 2020-09-09 12:28:12 UTC [ISS.0001.0008W] Microservices Functionality disabled due to license restrictions.
INFO   | jvm 1    | 2020/09/09 12:28:12 | ISSERVER|| 2020-09-09 12:28:12 UTC [ISS.0025.0049I] The JCE Unlimited Strength Jurisdiction Policy File was found
INFO   | jvm 1    | 2020/09/09 12:28:12 | ISSERVER|| 2020-09-09 12:28:12 UTC [ISS.0025.0041I] FIPS mode not initialized
INFO   | jvm 1    | 2020/09/09 12:28:15 | ISSERVER|| 2020-09-09 12:28:15 UTC [ISS.0025.0024I] JDBC Connection Manager started
INFO   | jvm 1    | 2020/09/09 12:28:19 | ISSERVER|| 2020-09-09 12:28:19 UTC [ISS.0095.0013I] Audit Logging initialized.
INFO   | jvm 1    | 2020/09/09 12:28:21 | ISSERVER|| 2020-09-09 12:28:21 UTC [ISS.0033.0168I] Cluster Node Name: 2e47862a24a0.
INFO   | jvm 1    | 2020/09/09 12:28:22 | ISSERVER|| 2020-09-09 12:28:22 UTC [ISS.0025.0017I] Repository Manager started
INFO   | jvm 1    | 2020/09/09 12:28:23 | ISSERVER|| 2020-09-09 12:28:23 UTC [ISS.0025.0021I] ACL Manager started
INFO   | jvm 1    | 2020/09/09 12:28:23 | ISSERVER|| 2020-09-09 12:28:23 UTC [ISS.0024.0015E] Integration Server cannot add group "My webMethods Users" to ACL "Anonymous" because group "My webMethods Users" is not found.
INFO   | jvm 1    | 2020/09/09 12:28:23 | ISSERVER|| 2020-09-09 12:28:23 UTC [ISS.0024.0015E] Integration Server cannot add group "My webMethods Users" to ACL "Default" because group "My webMethods Users" is not found.
INFO   | jvm 1    | 2020/09/09 12:28:23 | ISSERVER|| 2020-09-09 12:28:23 UTC [ISS.0024.0015E] Integration Server cannot add group "My webMethods Administrators" to ACL "Administrators" because group "My webMethods Administrators" is not found.
INFO   | jvm 1    | 2020/09/09 12:28:23 | ISSERVER|| 2020-09-09 12:28:23 UTC [ISS.0024.0015E] Integration Server cannot add group "My webMethods Administrators" to ACL "Internal" because group "My webMethods Administrators" is not found.
INFO   | jvm 1    | 2020/09/09 12:28:23 | ISSERVER|| 2020-09-09 12:28:23 UTC [ISS.0024.0015E] Integration Server cannot add group "My webMethods Administrators" to ACL "Developers" because group "My webMethods Administrators" is not found.
INFO   | jvm 1    | 2020/09/09 12:28:23 | ISSERVER|| 2020-09-09 12:28:23 UTC [ISS.0024.0015E] Integration Server cannot add group "My webMethods Administrators" to ACL "Replicators" because group "My webMethods Administrators" is not found.
INFO   | jvm 1    | 2020/09/09 12:28:23 | ISSERVER|| 2020-09-09 12:28:23 UTC [ISS.0025.0008I] State Manager started
INFO   | jvm 1    | 2020/09/09 12:28:23 | ISSERVER|| 2020-09-09 12:28:23 UTC [ISS.0025.0010I] Service Manager started
INFO   | jvm 1    | 2020/09/09 12:28:23 | ISSERVER|| 2020-09-09 12:28:23 UTC [ISS.0025.0020I] Validation Processor started
INFO   | jvm 1    | 2020/09/09 12:28:23 | ISSERVER|| 2020-09-09 12:28:23 UTC [ISS.0025.0022I] Statistics Processor started
INFO   | jvm 1    | 2020/09/09 12:28:23 | ISSERVER|| 2020-09-09 12:28:23 UTC [ISS.0025.0018I] Invoke Manager started
INFO   | jvm 1    | 2020/09/09 12:28:23 | ISSERVER|| 2020-09-09 12:28:23 UTC [ISS.0025.0012I] Cache Manager started
INFO   | jvm 1    | 2020/09/09 12:28:24 | ISSERVER|| 2020-09-09 12:28:24 UTC [ISS.0098.0026I] Document Store DefaultStore initialized
INFO   | jvm 1    | 2020/09/09 12:28:25 | ISSERVER|| 2020-09-09 12:28:25 UTC [ISS.0098.0026I] Document Store VolatileTriggerStore initialized
INFO   | jvm 1    | 2020/09/09 12:28:25 | ISSERVER|| 2020-09-09 12:28:25 UTC [ISS.0098.0026I] Document Store TriggerStore initialized
INFO   | jvm 1    | 2020/09/09 12:28:26 | ISSERVER|| 2020-09-09 12:28:26 UTC [ISS.0098.0026I] Document Store ResubmitStore initialized
INFO   | jvm 1    | 2020/09/09 12:28:26 | [Wed Sep 09 12:28:26.154 UTC 2020] [webMethods Integration Server] Setting log level to ERROR
INFO   | jvm 1    | 2020/09/09 12:28:26 | --------- Log File Closed ---------
INFO   | jvm 1    | 2020/09/09 12:28:26 |
INFO   | jvm 1    | 2020/09/09 12:28:26 |
INFO   | jvm 1    | 2020/09/09 12:28:27 | ISSERVER|| 2020-09-09 12:28:27 UTC [ISS.0153.0007I] webMethods Messaging connection alias IS_UM_CONNECTION started
INFO   | jvm 1    | 2020/09/09 12:28:27 | ISSERVER|| 2020-09-09 12:28:27 UTC [ISS.0153.0007I] webMethods Messaging connection alias IS_DES_CONNECTION started
INFO   | jvm 1    | 2020/09/09 12:28:27 | ISSERVER|| 2020-09-09 12:28:27 UTC [ISS.0153.0000I] webMethods Messaging subsystem started
INFO   | jvm 1    | 2020/09/09 12:28:28 | ISSERVER|| 2020-09-09 12:28:27 UTC [ISS.0106.0001I] Join Manager initialized
INFO   | jvm 1    | 2020/09/09 12:28:28 | ISSERVER|| 2020-09-09 12:28:27 UTC [ISS.0025.0032I] Dispatcher initialized
INFO   | jvm 1    | 2020/09/09 12:28:28 | ISSERVER|| 2020-09-09 12:28:28 UTC [ISS.0134.0128I] JMS subsystem is starting.
INFO   | jvm 1    | 2020/09/09 12:28:28 | ISSERVER|| 2020-09-09 12:28:28 UTC [ISS.0134.0998I] DEFAULT_IS_JMS_CONNECTION cache enabled :: true
INFO   | jvm 1    | 2020/09/09 12:28:28 | ISSERVER|| 2020-09-09 12:28:28 UTC [ISS.0134.0998I] PE_NONTRANSACTIONAL_ALIAS cache enabled :: true
INFO   | jvm 1    | 2020/09/09 12:28:28 | ISSERVER|| 2020-09-09 12:28:28 UTC [ISS.0134.0000I] JMS subsystem started
INFO   | jvm 1    | 2020/09/09 12:28:28 | ISSERVER|| 2020-09-09 12:28:28 UTC [ISS.0173.0000I] Common Messaging subsystem started
INFO   | jvm 1    | 2020/09/09 12:28:29 | ISSERVER|| 2020-09-09 12:28:29 UTC [SCC.0121.0093I] XA Recovery Store initialized
INFO   | jvm 1    | 2020/09/09 12:28:29 | ISSERVER|| 2020-09-09 12:28:29 UTC [com.webmethods.fabric.agent.FabricAgent.null.load.startI] [agent.load.start] Loading registry config file /opt/softwareag/IntegrationServer/instances/pont/config/fabric-agent.xml
INFO   | jvm 1    | 2020/09/09 12:28:29 | ISSERVER|| 2020-09-09 12:28:29 UTC [com.webmethods.fabric.agent.FabricAgent.null.startI] [agent.start] Starting Fabric Agent
INFO   | jvm 1    | 2020/09/09 12:28:29 | ISSERVER|| 2020-09-09 12:28:29 UTC [ISS.0025.0055I] Lock Manager initialized
INFO   | jvm 1    | 2020/09/09 12:28:30 | SLF4J: Class path contains multiple SLF4J bindings.
INFO   | jvm 1    | 2020/09/09 12:28:30 | SLF4J: Found binding in [jar:file:/opt/softwareag/common/lib/ext/log4j/log4j-slf4j-impl.jar!/org/slf4j/impl/StaticLoggerBinder.class]
INFO   | jvm 1    | 2020/09/09 12:28:30 | SLF4J: Found binding in [jar:file:/opt/softwareag/common/lib/ext/slf4j-log4j.jar!/org/slf4j/impl/StaticLoggerBinder.class]
INFO   | jvm 1    | 2020/09/09 12:28:30 | SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
INFO   | jvm 1    | 2020/09/09 12:28:30 | SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory]
INFO   | jvm 1    | 2020/09/09 12:28:35 | ISSERVER|| 2020-09-09 12:28:35 UTC [ISS.0014.0054C] Error occurred while initializing server: java.lang.ExceptionInInitializerError        at org.eclipse.jetty.http.pathmap.PathMappings.<clinit>(PathMappings.java:49)        at com.wm.app.b2b.server.websocket.WebSocketServerEndpointManager.<init>(WebSocketServerEndpointManager.java:34)     at com.wm.app.b2b.server.websocket.WebSocketServerEndpointManager.<clinit>(WebSocketServerEndpointManager.java:33)   at com.wm.app.b2b.server.Server.run(Server.java:665)
INFO   | jvm 1    | 2020/09/09 12:28:35 | check the server.log for more details
INFO   | jvm 1    | 2020/09/09 12:28:36 | [ProxyLogManager] reset() -> org.apache.logging.log4j.jul.LogManager@9248f73
INFO   | jvm 1    | 2020/09/09 12:28:37 | [Wed Sep 09 12:28:37 UTC 2020] Bootstrap: Stopping...
INFO   | jvm 1    | 2020/09/09 12:28:37 |
INFO   | jvm 1    | 2020/09/09 12:28:37 | !ENTRY com.softwareag.platform.hook.shutdown 1 0 2020-09-09 12:28:37.796
INFO   | jvm 1    | 2020/09/09 12:28:37 | !MESSAGE Stopping OSGi framework.
INFO   | jvm 1    | 2020/09/09 12:28:37 |
INFO   | jvm 1    | 2020/09/09 12:28:37 | !ENTRY com.softwareag.platform.hook.shutdown 1 0 2020-09-09 12:28:37.802
INFO   | jvm 1    | 2020/09/09 12:28:37 | !MESSAGE Framework shutdown timeout set to 120 seconds. Please also check tanuki-wrapper shutdown property, if specified.

Is the Integration Server using any external database in JDBC Pools, Just check if we are having connectivity from within the container as well. Not sure if something related to this is blocking the IS to come up.

Hi,
yes the server uses external JDBC Pools, also the JDBC doesn’t seem to cause the error :

2020-09-02 14:27:52 UTC [ISS.0025.0024I] JDBC Connection Manager started

I looked the logs of the normal IS and the next logs that should appear are :

[178]2020-09-09 15:34:54 CEST [ISS.0158.0000I] OData Server module is initialized.
[179]2020-09-09 15:34:54 CEST [ISS.0048.9999I] Successfully initialized module "odata-server" by com.softwareag.is.odata.core.Init
[180]2020-09-09 15:34:54 CEST [ISS.0048.9999I] Successfully initialized module "websocket-server" by com.softwareag.is.websocket.server.Init
[181]2020-09-09 15:34:54 CEST [ISS.0048.9999I] Successfully initialized module "graphql-server" by com.softwareag.is.graphql.Init
[182]2020-09-09 15:34:54 CEST [ISS.0139.9999I] Initializing ISKeyStoreManager using watt.server.homeDir:/FsSUP_env_dev/opt/application/efbsup/G12R00C00/is/IntegrationServer/instances/pont/config/security/keystore
[183]2020-09-09 15:34:54 CEST [ISS.0139.9999I] ISKeyStoreManager initialized successfully.

combined with the error logs, it’s seems that it’s the module “websocket-server” who don’t initialize correctly.

Can you please check what files exists under IntegrationServer/lib/jars directory in your Docker image? Please make sure that jetty related jars that exists in installed folder of IntegrationServer/lib/jars directory are also present in the Docker image that you create.

Regards,
-Kalpesh.

Hi,
in my IntegrationServer/lib :

agentInstall.jar       is-json-schema-parser.jar  is-websocket-api.jar     jcode.tmpl
dir.html               is-odata-api.jar           is-websocket-client.jar  mime.types
flow.tmpl              is-odata-client.jar        is-websocket-common.jar  wm-is-osgi-profile.jar
is-graphql-client.jar  is-odata-impl-v2.jar       is-websocket-server.jar  wm-isproxy.jar
is-graphql-server.jar  is-odata-server.jar        jars                     wm-isserver.jar

and in my IntegrationServer/lib/jars :

antlr4-runtime-4.7.1.jar       jetty-server-9.4.8.jar           postgresql-42.2.6.jar
btf-1.2.jar                    jetty-servlet-9.4.8.jar          reactive-streams-1.0.2.jar
commons-validator-1.6.0.jar    jetty-util-9.4.8.jar             rhino-1.7.7.1.jar
graphql-java-9.4.0.jar         jnpserver.jar                    scribe-1.3.0.jar
handy-uri-templates-2.1.6.jar  json-schema-core-1.2.5.jar       slf4j-api-1.7.25.jar
jackson-coreutils-1.8.jar      json-schema-validator-2.2.6.jar  spring-boot-actuator-1.5.7.RELEASE.jar.old
jackson-databind-2.6.5.jar     json-smart-1.1.1.jar             uri-template-0.9.jar
java-dataloader-2.0.2.jar      libphonenumber-6.2.jar           websocket-api-9.4.8.jar
jboss-common-core.jar          metrics-core-3.1.0.jar           websocket-client-9.4.8.jar
jboss-logging.jar              msg-simple-1.1.jar               websocket-common-9.4.8.jar
jetty-client-9.4.8.jar         nimbus-jose-jwt-5.1.jar          websocket-server-9.4.8.jar
jetty-http-9.4.8.jar           ojdbc6.jar                       websocket-servlet-9.4.8.jar
jetty-io-9.4.8.jar             olingo-odata2-api-2.0.10.jar     wm-fabric-agent.jar
jetty-security-9.4.8.jar       olingo-odata2-core-2.0.10.jar    xerces-parser-conf.jar

the only difference with my normal IS is an empty directory in IntegrationServer/lib and IntegrationServer/lib/jars, “classes” and “custom” respectively.

Hi Leroux,

I am wondering about the invalid license file message in the wrapper.log.
Did you check that?

Additionally from the IntegrationServer/lib/jars directory I see an ojdbc6.jar which is usually not part of the default installation.
This one should be moved to lib/jars/custom/ and should be replaced with an ojdbc8.jar from a more recent version of the driver available from Oracle.

Is IntegrationServer/ the working directory or IntegrationServer/instances/pont/?
Please check the lib-directory and subdirectories for the pont instance as well.

Regards,
Holger

Hi,

the license is the same used by the normal IS and should not create problems.

I will move the ojdbc.jar on the next restart of the normal IS and update the git.

What do you mean exactly by “working directory” ? I try to run the script “IntegrationServer/bin/startContainer.sh” with only one instance (pont).

The lib directory of “IntegrationServer/instances/pont/” is the same on my normal IS and my docker IS minus the empty directory.

“IntegrationServer/instances/pont/lib” :

classes  dir.html  flow.tmpl  jcode.tmpl  mime.types

“IntegrationServer/instances/pont/lib/classes” :

datasup.properties  log4j.properties  refalim.properties  refihm.properties

Do I have to copy my “IntegrationServer/lib” inside my “IntegrationServer/instances/pont/lib” ?

Hi Leroux,

I have never used Docker-Image until now as we are still migrating from 9.5 to 9.12.
Eventually we might use it later on.

Therefore I cannot tell which directories the Docker-Image is using.

Regards,
Holger

No, you don’t need to copy content under pont/lib directory. Did this work okay anytime in the past?
Can you try follow simple steps below and see if this works i.e. to remove any of the other steps in your setup.

From IntegrationServer/docker directory,

./is_container.sh createDockerfile -Dinstance.name=pont
./is_container.sh build
./is_container.sh createPackageDockerfile -Dinstance.name=pont
./is_container.sh buildPackage

Once the above steps are successful, you will see a Docker image is:micro in your env. Use docker run command to start the container and see if you see the similar failure?

Thanks,
-Kalpesh.

1 Like

Hi,

the I have create the is:micro image with the steps provided by Kalpesh, then i have compared the dockerfile of the generate image with my own dockerfile and fixed some difference. Now it’s work, but I have strange logs approximately each minute. I will work on it, but the main bug is fixed, so thank you for your precious help.

Regards

Hi Leroux,

would you mind to share the strange logs with us, please?

Eventually someone from the community knows what they mean and how to get rid of them.

Regards,
Holger

1 Like