IS not coming up

Vekatesh_Reddy · August 1, 2018, 5:49am

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.

Vekatesh_Reddy · August 1, 2018, 6:24am

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

Yogesh_Bhandari · August 1, 2018, 9:09am

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

Yogesh_Bhandari · August 1, 2018, 9:19am

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

Vekatesh_Reddy · August 1, 2018, 3:13pm

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