9.6 IS and Mediator not coming up after fresh installation

Hi,

I installed 9.6 Mediator and IS freshly, using external RDBMS.
But when i try to start it up, the log file is all ok, but am not able to open the UI.
Here is the server log:’

2018-11-07 11:28:11 CET [ISS.0025.0006I] License Manager started
2018-11-07 11:28:11 CET [ISS.0001.0008W] Publish / Subscribe Functionality disabled due to license restrictions.
2018-11-07 11:28:11 CET [ISS.0001.0008W] Adapter Runtime Functionality disabled due to license restrictions.
2018-11-07 11:28:11 CET [ISS.0001.0008W] Remote Invoke Functionality disabled due to license restrictions.
2018-11-07 11:28:11 CET [ISS.0001.0008W] Guaranteed Delivery Functionality disabled due to license restrictions.
2018-11-07 11:28:11 CET [ISS.0001.0008W] Distributed Cache Functionality disabled due to license restrictions.
2018-11-07 11:28:13 CET [ISS.0025.0050W] The JCE Unlimited Strength Jurisdiction Policy File was not found. Please install it.
2018-11-07 11:28:13 CET [ISS.0025.0041I] FIPS mode not initialized
2018-11-07 11:28:15 CET [ISS.0025.0017I] Repository Manager started
2018-11-07 11:28:17 CET [ISS.0025.0024I] JDBC Connection Manager started
2018-11-07 11:28:19 CET [ISS.0095.0013I] Audit Logging initialized.
2018-11-07 11:28:20 CET [CommonLib.MWS.0002I] Initializing component: com.webMethods.rtl.util.xml.XMLUtil
2018-11-07 11:28:20 CET [CommonLib.MWS.0002I] Loading phase: [phaseID, phaseName] [miePhaseTwo, miePhaseTwo]
2018-11-07 11:28:20 CET [CommonLib.MWS.0002I] Initializing components of: miePhaseTwo
2018-11-07 11:28:20 CET [CommonLib.MWS.0002I] Initializing component: com.webMethods.portal.system.cluster.impl.ClusterProvider
2018-11-07 11:28:20 CET [CommonLib.MWS.0002I] Initializing components of: cluster
2018-11-07 11:28:20 CET [CommonLib.MWS.0002I] JMS ClientID: ux311v006.ica.ia-hc.net.mws.library.4472765437650725888
2018-11-07 11:28:20 CET [CommonLib.MWS.0002I] Initializing components of: null
2018-11-07 11:28:20 CET [CommonLib.MWS.0002I] Initializing server: $cds
2018-11-07 11:28:20 CET [CommonLib.MWS.0002I] Updated the hostname for the RMI server to: ux311v006.ica.ia-hc.net
2018-11-07 11:28:20 CET [CommonLib.MWS.0002I] Initializing component: com.webMethods.event.ecs.ECSInitializer
2018-11-07 11:28:20 CET [CommonLib.MWS.0002I] Initializing component: com.webMethods.portal.service.global.impl.GlobalProvider
2018-11-07 11:28:20 CET [CommonLib.MWS.0002I] Initializing components of: global
2018-11-07 11:28:21 CET [CommonLib.MWS.0002I] Loading phase: [phaseID, phaseName] [DataPhaseOne, DataPhaseOne]
2018-11-07 11:28:21 CET [CommonLib.MWS.0002I] Initializing components of: DataPhaseOne
2018-11-07 11:28:21 CET [CommonLib.MWS.0002I] Initializing component: com.webMethods.portal.event.EventDeliveryInitializer
2018-11-07 11:28:21 CET [CommonLib.MWS.0002I] Initializing component: com.webMethods.portal.service.sql.core.ConnectionManager
2018-11-07 11:28:21 CET [CommonLib.MWS.0002I] No registered exception delegate
2018-11-07 11:28:21 CET [CommonLib.MWS.0002I] webMethods Integration Server Shutting down…
2018-11-07 11:28:21 CET [CommonLib.MWS.0002I] StackTrace for shutdown - this is an expect info message only :
java.lang.Thread.getStackTrace(Thread.java:1588)
com.webMethods.portal.system.PortalSystem.shutdown(PortalSystem.java:933)
com.webMethods.portal.system.PortalSystem.main(PortalSystem.java:867)
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:558)
com.wm.app.b2b.server.UserManager.init(UserManager.java:489)
com.wm.app.b2b.server.Server.run(Server.java:394)

2018-11-07 11:28:21 CET [CommonLib.MWS.0002I] Shutting down components of: PortalServer
2018-11-07 11:28:21 CET [CommonLib.MWS.0002I] Shutting down components of: miePhaseTwo
2018-11-07 11:28:21 CET [CommonLib.MWS.0002I] Shutting down components of: global
2018-11-07 11:28:21 CET [CommonLib.MWS.0002I] Shutting down components of: cluster
2018-11-07 11:28:21 CET [CommonLib.MWS.0002I] Shutting down components of: miePhaseOne
2018-11-07 11:28:21 CET [CommonLib.MWS.0002I] Stopped sending JDK log messages to Log4j
2018-11-07 11:28:21 CET [ISS.0024.0010C] Central User Manager initialization failed with error : [POP.004.0201] Cannot initialize MWS, invalid database schema is specified
2018-11-07 11:28:21 CET [ISS.0024.0001E] Could not initialize User Manager.
2018-11-07 11:28:21 CET [ISS.0025.0021I] ACL Manager started
2018-11-07 11:28:21 CET [ISS.0033.0168C] Cluster Node Name: ux311v006.ica.ia-hc.net.
2018-11-07 11:28:21 CET [ISS.0025.0008I] State Manager started
2018-11-07 11:28:21 CET [ISS.0025.0010I] Service Manager started
2018-11-07 11:28:21 CET [ISS.0025.0020I] Validation Processor started
2018-11-07 11:28:21 CET [ISS.0025.0022I] Statistics Processor started
2018-11-07 11:28:21 CET [ISS.0025.0018I] Invoke Manager started
2018-11-07 11:28:21 CET [ISS.0025.0012I] Cache Manager started
2018-11-07 11:28:21 CET [ISS.0134.0998I] DEFAULT_IS_JMS_CONNECTION cache enabled :: true
2018-11-07 11:28:21 CET [ISS.0134.0998I] EventBus cache enabled :: true
2018-11-07 11:28:21 CET [ISS.0134.0998I] PE_NONTRANSACTIONAL_ALIAS cache enabled :: true
2018-11-07 11:28:21 CET [ISS.0134.0000I] JMS subsystem started
2018-11-07 11:28:21 CET [SCC.0121.0093I] XA Recovery Store initialized
2018-11-07 11:28:22 CET [ISS.0025.0064E] EDA Manager initialized.
2018-11-07 11:28:22 CET [agent.load.tartI] Loading registry config file /opt/webMethods/IntegrationServer/instances/default/config/fabric-agent.xml
2018-11-07 11:28:22 CET [com.webMethods.fabric.agent.FabricAgent.agent.startI] Starting Fabric Agent
2018-11-07 11:28:22 CET [ISS.0025.0055I] Lock Manager initialized
2018-11-07 11:28:24 CET [ISS.0025.0004I] Flow Service Manager started
2018-11-07 11:28:24 CET [ISS.0025.0002I] Package Manager started
2018-11-07 11:28:24 CET [ISS.0025.0011I] Package Replicator Manager started
2018-11-07 11:28:24 CET [ISS.0028.0001I] Loading packages
2018-11-07 11:28:25 CET [ISS.0028.0005I] Loading WmRoot package
2018-11-07 11:28:28 CET [ISS.0028.0005I] Loading WmPublic package
2018-11-07 11:28:28 CET [ISS.0028.0005I] Loading WmWin32 package
2018-11-07 11:28:28 CET [ISS.0028.0005I] Loading WmISExtDC package
2018-11-07 11:28:28 CET [ISS.0028.0005I] Loading WmXSLT package
2018-11-07 11:28:28 CET [ISS.0028.0005I] Loading WmAssetPublisher package
2018-11-07 11:28:28 CET [ISS.0028.0005I] Loading WmVCS package
2018-11-07 11:28:28 CET [ISS.0028.0005I] Loading WmTomcat package
2018-11-07 11:28:28 CET [ISS.0028.0005I] Loading WmFlatFile package
2018-11-07 11:28:28 CET [ISS.0028.0005I] Loading WmMediator package
2018-11-07 11:28:28 CET [ISS.0028.0005I] Loading WmART package
2018-11-07 11:28:28 CET [ISS.0028.0005I] Loading WmARTExtDC package
2018-11-07 11:28:30 CET [ISS.0028.0005I] Loading Default package
2018-11-07 11:28:32 CET [ISS.0028.0012I] WmRoot: Startup service (wm.server.event:startEventAgentPort)
2018-11-07 11:28:32 CET [ISS.0028.0012I] WmRoot: Startup service (wm.server.net.email:init)
2018-11-07 11:28:32 CET [ISS.0028.0012I] WmRoot: Startup service (wm.server.net.ftp:init)
2018-11-07 11:28:32 CET [ISS.0028.0012I] WmRoot: Startup service (wm.server.net.ftps:init)
2018-11-07 11:28:32 CET [ISS.0028.0012I] WmRoot: Startup service (wm.server.net.http:init)
2018-11-07 11:28:33 CET [ISS.0028.0012I] WmRoot: Startup service (wm.server.ns:syncToNamespace)
2018-11-07 11:28:33 CET [ISS.0028.0012I] WmRoot: Startup service (wm.server.remote:init)
2018-11-07 11:28:33 CET [ISS.0028.0012I] WmRoot: Startup service (wm.server.proxy:init)
2018-11-07 11:28:33 CET [ISS.0028.0012I] WmRoot: Startup service (wm.server.schedule:init)
2018-11-07 11:28:33 CET [ISS.0028.0012I] WmRoot: Startup service (wm.server.dispatcher:init)
2018-11-07 11:28:33 CET [ISS.0028.0012I] WmRoot: Startup service (wm.server.tx:init)
2018-11-07 11:28:33 CET [ISC.0063.0011I] Start
2018-11-07 11:28:33 CET [ISS.0028.0012I] WmRoot: Startup service (wm.server.soap:init)
2018-11-07 11:28:33 CET [ISS.0028.0012I] WmRoot: Startup service (wm.server.tspace:init)
2018-11-07 11:28:33 CET [ISS.0028.0012I] WmRoot: Startup service (wm.server.ws:init)
2018-11-07 11:28:33 CET [ISS.0028.0012I] WmRoot: Startup service (wm.server.oauth:init)
2018-11-07 11:28:33 CET [ISS.0028.0012I] WmRoot: Startup service (wm.server.security.keystore:createDefaultKeyStoreTrustStoreAliases)
2018-11-07 11:28:33 CET [ISS.0028.0012I] WmPublic: Startup service (pub.storage:startup)
2018-11-07 11:28:33 CET [ISS.0028.0012I] WmXSLT: Startup service (wm.xslt.Admin:startup)
2018-11-07 11:28:33 CET [ISS.0028.0012I] WmAssetPublisher: Startup service (wm.server.metadata.Admin:startup)
2018-11-07 11:28:33 CET [ISS.0138.0050I] Asset Publisher package initializing
2018-11-07 11:28:33 CET [ISS.0028.0012I] WmMediator: Startup service (pg.config:init)
2018-11-07 11:28:33 CET [MED.0010.0054I] PackageLifeCycleHelper - ACL for Mediator Deployer not defined, define it now.
2018-11-07 11:28:34 CET [MED.0010.0002I] WS-Stack repository located at /opt/webMethods/IntegrationServer/instances/default/config/wss/
2018-11-07 11:28:34 CET [MED.0010.0003I] WS-Stack configuration file located: /opt/webMethods/IntegrationServer/instances/default/config/wss//axis2.xml
2018-11-07 11:28:34 CET [MED.0010.0175I]
Mediator product is initialized.

2018-11-07 11:28:35 CET [MED.0205.0500I] Audit sender startup
2018-11-07 11:28:35 CET [MED.0010.0045I] Mediator Spring context loaded.
2018-11-07 11:28:35 CET [MED.0205.0021I] LifeCycleEventPublisher - startup method is called
2018-11-07 11:28:35 CET [MED.0205.0713I] Not sending Life Cycle Event to EDA destination
2018-11-07 11:28:35 CET [ISS.0028.0012I] WmMediator: Startup service (pg.UIConfig:initUI)
2018-11-07 11:28:35 CET [ISS.0028.0012I] WmISExtDC: Startup service (com.wm.isextdc.PkgInit:init)
2018-11-07 11:28:35 CET [ISS.0028.0012I] WmFlatFile: Startup service (wm.server.ff:init)
2018-11-07 11:28:35 CET [ISS.0028.0012I] WmFlatFile: Startup service (wm.server.ff:setACLs)
2018-11-07 11:28:35 CET [ISS.0028.0012I] WmVCS: Startup service (wm.server.vcsadmin:startup)
2018-11-07 11:28:35 CET [VCS.0132.0050I] VCS package initializing
2018-11-07 11:28:35 CET [ISS.0028.0012I] WmVCS: Startup service (wm.server.vcsui:addSolutions)
2018-11-07 11:28:35 CET [ISS.0028.0012I] WmART: Startup service (wm.art.admin:startup)
2018-11-07 11:28:35 CET [ISS.0028.0012I] WmTomcat: Startup service (wm.tomcat.admin:startup)
2018-11-07 11:28:36 CET [ISS.0100.0001C] Web Container started
2018-11-07 11:28:36 CET [ISS.0028.0012I] WmARTExtDC: Startup service (com.wm.artextdc.pkginit:init)
2018-11-07 11:28:36 CET [ISP.0046.0012I] Enabling HTTP Listener on port 9999
2018-11-07 11:28:36 CET [ISP.0046.0012I] Enabling HTTP Listener on port 9644
2018-11-07 11:28:36 CET [ISS.0025.0013I] Cache Sweeper started
2018-11-07 11:28:36 CET [ISS.0014.0002C] Initialization completed in 25 seconds.
2018-11-07 11:28:36 CET [ISS.0025.0016I] Config File Directory Saved
2018-11-07 11:28:36 CET [ISS.0025.0057I] Reading system level information
2018-11-07 11:28:36 CET [MED.0010.0319W] Registered consumer names file [/opt/webMethods/IntegrationServer/instances/default/packages/WmMediator/config/resources/consumers/registeredNames.xml] could not be found! Could not load RegisteredNameType information into Mediator.
2018-11-07 11:28:49 CET [MED.0204.0013W] No value defined in CCC cache for this key: currentInterval

Regards
Jyoti

Hi Jyoti,

can you please check the database user in MWS JDBC Pool (assigned to function “Central Users”)
The log file points to an incorrect schema for this pool.

Beside that I cannot detect any hint why IS Admin UI should not be displayed.

Is there any error message in Browser when trying to access the UI via Ports 9999 or 9644?

Are there any Fixes applied to IS?

Regards,
Holger

Hi Holger,

Thank you so much for all your help. :slight_smile:
The issue was with the firewall. we opened it for the new port and the IS is up and running.

Regards
Jyoti