IS not coming up

Hi,
I am getting below error while starting IS. Can you pls help me?

2018-07-31 21:33:53 MDT [ISS.0028.0005I] Loading WmARTExtDC package
2018-07-31 21:33:54 MDT [ISS.0028.0005I] Loading WmJDBCAdapter package
2018-07-31 21:33:54 MDT [ART.0115.3709D] Adapter Runtime (Listener): Listener Manager waiting on Integration Server initialization.

2018-07-31 22:19:38 MDT [ISS.0025.0001C] Integration Server 9.8.0.0 Build 247
2018-07-31 22:19:39 MDT [ISS.0025.0006I] License Manager started
2018-07-31 22:19:40 MDT [ISS.0025.0049I] The JCE Unlimited Strength Jurisdiction Policy File was found
2018-07-31 22:20:01 MDT [ISS.0025.0041I] FIPS mode not initialized
2018-07-31 22:20:03 MDT [BAS.0123.0001I] FSData full consistency check is being performed. Db:WmRepository4/RepoV4
2018-07-31 22:20:05 MDT [ISS.0025.0017I] Repository Manager started
2018-07-31 22:20:09 MDT [ISS.0025.0024I] JDBC Connection Manager started
2018-07-31 22:20:15 MDT [ISS.0095.0013I] Audit Logging initialized.
2018-07-31 22:20:18 MDT [ISS.0033.0168C] Cluster Node Name: wmidevcgy004.
2018-07-31 22:20:19 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.rtl.util.xml.XMLUtil
2018-07-31 22:20:19 MDT [CommonLib.MWS.0002I] Loading phase: [phaseID, phaseName] [miePhaseTwo, miePhaseTwo]
2018-07-31 22:20:19 MDT [CommonLib.MWS.0002I] Initializing components of: miePhaseTwo
2018-07-31 22:20:19 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.service.sql.core.ConnectionManager
2018-07-31 22:20:19 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.system.cluster.impl.ClusterProvider
2018-07-31 22:20:19 MDT [CommonLib.MWS.0002I] Initializing components of: cluster
2018-07-31 22:20:19 MDT [CommonLib.MWS.0002I] JMS ClientID: wmidevcgy004.mws.library.4424970272431906816
2018-07-31 22:20:19 MDT [CommonLib.MWS.0002I] Initializing components of: null
2018-07-31 22:20:19 MDT [CommonLib.MWS.0002I] The hostname for the SOAP Monitor is not specified, using defaults.
2018-07-31 22:20:19 MDT [CommonLib.MWS.0002I] The hostname for the JCR Remoting is not specified, using defaults.
2018-07-31 22:20:19 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.event.ecs.ECSInitializer
2018-07-31 22:20:19 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.service.global.impl.GlobalProvider
2018-07-31 22:20:19 MDT [CommonLib.MWS.0002I] Initializing components of: global
2018-07-31 22:20:21 MDT [CommonLib.MWS.0002I] Loading phase: [phaseID, phaseName] [DataPhaseOne, DataPhaseOne]
2018-07-31 22:20:21 MDT [CommonLib.MWS.0002I] Initializing components of: DataPhaseOne
2018-07-31 22:20:21 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.event.EventDeliveryInitializer
2018-07-31 22:20:21 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.system.impl.BuildVersion
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.event.EventDeliveryInitializer
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.service.sql.DataManager
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.jms.JMSPhaseProvider
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] wm.db.jms.conn.purge.time set to 60
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] JMSProvider.onReconnect entering for topics
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] DbJMSConnProvider initialized successfully
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.service.cache.impl.CacheManager
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Cache Invalidation Thread started with sleep of 60000ms
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing components of: cache
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] defaultCacheTimeout: + 3600000(sec) roleCacheLifecyle: 1 (0=FLUSH_EVERY_LOGIN 1=FLUSH_ROLE_OR_GROUP_CHANGE)
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] defaultCacheTimeout: + 3600000(sec) groupCacheLifecycle: 1 (0=FLUSH_EVERY_LOGIN 1=FLUSH_ROLE_OR_GROUP_CHANGE)
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.service.meta2.impl.MetaManager
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing components of: meta
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing components of: default
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing com.webmethods.portal.service.meta2.impl.ThingIDService
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing com.webmethods.portal.service.meta2.impl.ThingService
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing com.webmethods.portal.service.meta2.impl.XTypeService
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing com.webmethods.portal.service.meta2.dbo.DBOStorageService
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing com.webmethods.portal.service.meta2.impl.MetaQueryService
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing com.webmethods.portal.service.meta2.impl.TypeService
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing com.webmethods.portal.service.meta2.impl.UserService
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing com.webmethods.portal.service.meta2.impl.RelationService
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing com.webmethods.portal.service.meta2.impl.MetaEventService
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing com.webmethods.portal.service.meta2.impl.MetaViewService
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing com.webmethods.portal.service.meta2.impl.AccessService
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing com.webmethods.portal.service.meta2.impl.ServiceThingService
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.mech.alias.impl.AliasMechanics
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Loading phase: [phaseID, phaseName] [CoreServices, CoreServices]
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing components of: CoreServices
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.service.dir.impl.DirSystem
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing components of: directory
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.service.portlet.impl.PortletProvider
2018-07-31 22:20:22 MDT [CommonLib.MWS.0002I] Initializing components of: portlet
2018-07-31 22:20:24 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.event.EventDeliveryInitializer
2018-07-31 22:20:24 MDT [CommonLib.MWS.0002I] Created New subscription: 6006 for wmidevcgy004.mws.library.4424970272431906816.com.webmethods.nis.events
2018-07-31 22:20:24 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.bizPolicy.impl.ContextProvider
2018-07-31 22:20:24 MDT [CommonLib.MWS.0002I] Initializing components of: context
2018-07-31 22:20:24 MDT [CommonLib.MWS.0002I] Loading phase: [phaseID, phaseName] [mechanics, mechanics]
2018-07-31 22:20:24 MDT [CommonLib.MWS.0002I] Initializing components of: mechanics
2018-07-31 22:20:24 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.mech.impl.MechanicsManager
2018-07-31 22:20:24 MDT [CommonLib.MWS.0002I] Initializing components of: mechanics
2018-07-31 22:20:27 MDT [CommonLib.MWS.0002W] com.webmethods.portal.portlet.wm_xt_portalrules.mechanics.PortalRulesMechanics
2018-07-31 22:20:27 MDT [CommonLib.MWS.0002W] Unable to initialize Component: wm_xt_portalrules Null class returned
2018-07-31 22:20:28 MDT [CommonLib.MWS.0002I] Loading phase: [phaseID, phaseName] [bizPolicy, bizPolicy]
2018-07-31 22:20:28 MDT [CommonLib.MWS.0002I] Initializing components of: bizPolicy
2018-07-31 22:20:28 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.bizPolicy.biz.impl.BizPolicyManager
2018-07-31 22:20:28 MDT [CommonLib.MWS.0002I] Initializing components of: bizPolicy
2018-07-31 22:20:28 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.bizPolicy.command.impl.CommandManager
2018-07-31 22:20:28 MDT [CommonLib.MWS.0002I] Initializing components of: command
2018-07-31 22:20:28 MDT [CommonLib.MWS.0002I] Loading phase: [phaseID, phaseName] [postInit, postInit]
2018-07-31 22:20:28 MDT [CommonLib.MWS.0002I] Initializing components of: postInit
2018-07-31 22:20:28 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.service.meta2.dbo.PostInit
2018-07-31 22:20:29 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.bizPolicy.impl.PostInitContextProvider
2018-07-31 22:20:29 MDT [CommonLib.MWS.0002I] Initializing component: com.webmethods.portal.noodle.preferences.UserPreferences
2018-07-31 22:20:29 MDT [CommonLib.MWS.0002I] Dir Cache initialized: com.webmethods.portal.portlet.wm_xt_sysdirsvc.service.SystemDirCache:m_cacheEnabled = [true];m_authenticateCacheEnabled = [true];m_dnCacheEnabled = [true];m_queryCacheEnabled = [false];m_uriCacheEnabled = [true];m_timeout = [2147483646];m_authenticateTimeout = [120000];m_capacity = [1000];m_authenticateCapacity = [1000];
2018-07-31 22:20:29 MDT [CommonLib.MWS.0002I] No registered exception delegate
2018-07-31 22:20:30 MDT [CommonLib.MWS.0002I] Dir Cache initialized: com.webmethods.portal.portlet.wm_xt_ldapdirsvc.service.LdapDirCache:m_cacheEnabled = [true];m_authenticateCacheEnabled = [true];m_dnCacheEnabled = [true];m_queryCacheEnabled = [true];m_uriCacheEnabled = [true];m_timeout = [60000];m_authenticateTimeout = [120000];m_capacity = [1000];m_authenticateCapacity = [1000];
2018-07-31 22:20:30 MDT [CommonLib.MWS.0002I] Registering datasource: New Oracle
2018-07-31 22:20:30 MDT [CommonLib.MWS.0002I] Registering datasource: ProcessAudit
2018-07-31 22:20:30 MDT [ISS.0024.0012I] Central User Management initialized successfully.
2018-07-31 22:20:31 MDT [ISS.0025.0021I] ACL Manager started
2018-07-31 22:20:32 MDT [ISS.0025.0008I] State Manager started
2018-07-31 22:20:32 MDT [ISS.0025.0010I] Service Manager started
2018-07-31 22:20:32 MDT [ISS.0025.0020I] Validation Processor started
2018-07-31 22:20:32 MDT [ISS.0025.0022I] Statistics Processor started
2018-07-31 22:20:32 MDT [ISS.0025.0018I] Invoke Manager started
2018-07-31 22:20:32 MDT [ISS.0025.0012I] Cache Manager started
2018-07-31 22:20:33 MDT [BAS.0123.0001I] FSData full consistency check is being performed. Db:DocumentStore/ISTransStore
2018-07-31 22:20:34 MDT [ISS.0098.0026I] Document Store DefaultStore initialized
2018-07-31 22:20:35 MDT [BAS.0123.0001I] FSData full consistency check is being performed. Db:DocumentStore/TriggerStore
2018-07-31 22:20:39 MDT [ISS.0098.0026I] Document Store VolatileTriggerStore initialized
2018-07-31 22:20:39 MDT [ISS.0098.0026I] Document Store TriggerStore initialized
2018-07-31 22:20:39 MDT [BAS.0123.0001I] FSData full consistency check is being performed. Db:DocumentStore/ISResubmitStore
2018-07-31 22:20:40 MDT [ISS.0098.0026I] Document Store ResubmitStore initialized
2018-07-31 22:20:40 MDT [ISS.0153.0000I] webMethods Messaging subsystem started
2018-07-31 22:20:40 MDT [ISS.0106.0001I] Join Manager initialized
2018-07-31 22:20:40 MDT [ISS.0025.0032I] Dispatcher initialized
2018-07-31 22:20:40 MDT [ISS.0134.0128I] JMS subsystem is starting.
2018-07-31 22:20:41 MDT [ISS.0134.0000I] JMS subsystem started
2018-07-31 22:20:41 MDT [BAS.0123.0001I] FSData full consistency check is being performed. Db:XAStore/DefaultXAStore
2018-07-31 22:20:41 MDT [SCC.0121.0093I] XA Recovery Store initialized
2018-07-31 22:20:41 MDT [agent.load.tartI] Loading registry config file /WM-data/IS-sap/IntegrationServer/instances/IS1/config/fabric-agent.xml
2018-07-31 22:20:42 MDT [com.webmethods.fabric.agent.FabricAgent.agent.startI] Starting Fabric Agent
2018-07-31 22:20:42 MDT [ISS.0025.0055I] Lock Manager initialized
2018-07-31 22:20:49 MDT [ISS.0048.9999I] Module odata-server initialized. OData Server module is initialized.
2018-07-31 22:20:49 MDT [ISS.0048.9999I] Successfully initialized module “odata-server” by com.softwareag.is.odata.core.Init
2018-07-31 22:20:49 MDT [ISS.0139.9999I] Initializing ISKeyStoreManager using watt.server.homeDir:/WM-data/IS-sap/IntegrationServer/instances/IS1/config/security/keystore
2018-07-31 22:20:49 MDT [ISS.0139.9999I] ISKeyStoreManager initialized successfully.
2018-07-31 22:20:50 MDT [ISS.0025.0004I] Flow Service Manager started
2018-07-31 22:20:50 MDT [ISS.0025.0002I] Package Manager started
2018-07-31 22:20:50 MDT [ISS.0025.0011I] Package Replicator Manager started
2018-07-31 22:20:50 MDT [ISS.0028.0001I] Loading packages
2018-07-31 22:20:56 MDT [ISS.0028.0005I] Loading WmRoot package
2018-07-31 22:21:06 MDT [ISS.0028.0005I] Loading WmPublic package
2018-07-31 22:21:06 MDT [ISS.0028.0005I] Loading WmDeployerResource package
2018-07-31 22:21:07 MDT [ISS.0028.0005I] Loading WmNUMDeployer package
2018-07-31 22:21:07 MDT [ISS.0028.0005I] Loading WmEstdCommonLib package
2018-07-31 22:21:07 MDT [ISS.0028.0005I] Loading WmBrokerDeployer package
2018-07-31 22:21:07 MDT [ISS.0028.0005I] Loading WmPRT package
2018-07-31 22:21:08 MDT [ISS.0028.0005I] Loading WmLogUtil package
2018-07-31 22:21:08 MDT [ISS.0028.0005I] Loading WmDesigner package
2018-07-31 22:21:08 MDT [ISS.0028.0005I] Loading WmXSLT package
2018-07-31 22:21:08 MDT [ISS.0028.0005I] Loading WmMonitor package
2018-07-31 22:21:09 MDT [ISS.0028.0005I] Loading WmAssetPublisher package
2018-07-31 22:21:09 MDT [ISS.0028.0005I] Loading WmISExtDC package
2018-07-31 22:21:10 MDT [ISS.0028.0005I] Loading WmCloud package
2018-07-31 22:21:10 MDT [ISS.0028.0005I] Loading WmVCS package
2018-07-31 22:21:12 MDT [ISS.0028.0005I] Loading WmART package
2018-07-31 22:21:12 MDT [ISS.0028.0005I] Loading WmFlatFile package
2018-07-31 22:21:13 MDT [ISS.0028.0005I] Loading WmTomcat package
2018-07-31 22:21:13 MDT [ISS.0028.0005I] Loading WmARTExtDC package
2018-07-31 22:21:13 MDT [ISS.0028.0005I] Loading WmJDBCAdapter package
2018-07-31 22:21:14 MDT [ART.0115.3709D] Adapter Runtime (Listener): Listener Manager waiting on Integration Server initialization.
2018-07-31 22:21:14 MDT [ISS.0028.0005I] Loading WmSAP package
2018-07-31 22:21:15 MDT [ISS.0028.0005I] Loading WmEDI package
2018-07-31 22:21:15 MDT [ART.0115.3711D] Adapter Runtime (Listener): Register() Queued for Listener:wm.sap.internal.ls:routingListener.
2018-07-31 22:21:17 MDT [ART.0115.3709D] Adapter Runtime (Listener): Listener Manager waiting on Integration Server initialization.
2018-07-31 22:21:18 MDT [ISS.0028.0005I] Loading WmDeployer package
2018-07-31 22:21:18 MDT [ISS.0028.0005I] Loading WmTN package
2018-07-31 22:21:20 MDT [ART.0115.3709D] Adapter Runtime (Listener): Listener Manager waiting on Integration Server initialization.
2018-07-31 22:21:22 MDT [CommonLib.MWS.0002W] Deleting inactive subscription: 5998
2018-07-31 22:21:23 MDT [ART.0115.3709D] Adapter Runtime (Listener): Listener Manager waiting on Integration Server initialization.
2018-07-31 22:21:26 MDT [ART.0115.3709D] Adapter Runtime (Listener): Listener Manager waiting on Integration Server initialization.
2018-07-31 22:21:29 MDT [ART.0115.3709D] Adapter Runtime (Listener): Listener Manager waiting on Integration Server initialization.
2018-07-31 22:21:32 MDT [ART.0115.3709D] Adapter Runtime (Listener): Listener Manager waiting on Integration Server initialization.
2018-07-31 22:21:32 MDT [ISS.0028.0005I] Loading WmTNExtDC package
^C

Hi Vekatesh,

Please provide wrapper.log and platform.log (under profiles/<instance_name>/logs)as there is not much info in server log.

Also are you server is in cluster ? If yes, then please confirm if TC server is running.

Thanks,
Yogesh

Also please make sure you have not any connectivity issue with any backend systems and databases which are configured for the JDBC Adapter connections.

Thanks Yogesh for the quick response.
IS is not running in cluster.
All the backend connections to DB looks good.
I started IS in safemode and see that all JDBC pools looks good.

I will take a look at wrapper.log file and will let you know if I see any thing.
This morning, when we tried bringing up the IS, it came up fine (Though at startup it was giving same error)
Interesting stuff :slight_smile: