It's all about the answers!

Ask a question

Error starting bfengine.exe in command line


Jirong Hu (1.5k6264256) | asked Aug 31 '15, 10:29 p.m.
We have a newly installed BF v8.0.0.2, the Mgmt Console service can be started, but can't run the job. It's always in the Waiting, Not Started. We can connecting this to a v7 license server, to confirm this is the issue, I run "bfengine.exe -d" from command line, then I got the following error:

 Aug 31, 2015 10:24:09 PM com.buildforge.services.server.plugin.PluginDeployer deploy
INFO: Deploying 'com.ibm.rational.connector.buildforge.services.plugin.jar' to '
D:\Program Files (x86)\IBM\Build Forge1\services-plugins\com.ibm.rational.connector.buildforge.services.plugin'
Aug 31, 2015 10:24:09 PM com.buildforge.services.server.plugin.PluginDeployer deploy
SEVERE: Unable to deploy jar file 'com.ibm.rational.connector.buildforge.services.plugin.jar' to directory 'com.ibm.rational.connector.buildforge.services.plugin'
java.io.FileNotFoundException: D:\Program Files (x86)\IBM\Build Forge1\services-plugins\com.ibm.rational.connector.buildforge.services.plugin\META-INF\MANIFEST.MF (Access is denied.)
        at java.io.FileOutputStream.<init>(FileOutputStream.java:233)
        at java.io.FileOutputStream.<init>(FileOutputStream.java:183)
        at com.buildforge.services.server.plugin.PluginDeployer.extractJarFile(P
luginDeployer.java:168)
        at com.buildforge.services.server.plugin.PluginDeployer.deploy(PluginDep
loyer.java:54)
        at com.buildforge.services.server.plugin.PluginMonitor.run(PluginMonitor
.java:82)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.
java:1157)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor
.java:627)
        at java.lang.Thread.run(Thread.java:801)

Aug 31, 2015 10:24:24 PM com.buildforge.services.server.plugin.PluginDeployer de
ploy
INFO: Deploying 'com.ibm.rational.connector.buildforge.services.plugin.jar' to '
D:\Program Files (x86)\IBM\Build Forge1\services-plugins\com.ibm.rational.connec
tor.buildforge.services.plugin'
Aug 31, 2015 10:24:24 PM com.buildforge.services.server.plugin.PluginDeployer de
ploy
SEVERE: Unable to deploy jar file 'com.ibm.rational.connector.buildforge.service
s.plugin.jar' to directory 'com.ibm.rational.connector.buildforge.services.plugi
n'
java.io.FileNotFoundException: D:\Program Files (x86)\IBM\Build Forge1\services-
plugins\com.ibm.rational.connector.buildforge.services.plugin\META-INF\MANIFEST.
MF (Access is denied.)
        at java.io.FileOutputStream.<init>(FileOutputStream.java:233)
        at java.io.FileOutputStream.<init>(FileOutputStream.java:183)
        at com.buildforge.services.server.plugin.PluginDeployer.extractJarFile(P
luginDeployer.java:168)
        at com.buildforge.services.server.plugin.PluginDeployer.deploy(PluginDep
loyer.java:54)
        at com.buildforge.services.server.plugin.PluginMonitor.run(PluginMonitor
.java:82)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.
java:1157)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor
.java:627)
        at java.lang.Thread.run(Thread.java:801)

4 answers



permanent link
Spencer Murata (2.3k115870) | answered Sep 01 '15, 8:56 a.m.
FORUM MODERATOR / JAZZ DEVELOPER
 Sounds like a file permission error.  By default BF will attempt to expand and deploy the plugin com.ibm.rational.connector.buildforge.services.plugin.jar in the services-plugins at the BF install directory.  So there is a problem with the permissions with the bfengine.exe then it won't be able to expand it.  Check what user is starting the bfengine executable and that they have modify permissions to the install directory.

~Spencer

Comments
Jirong Hu commented Sep 01 '15, 10:43 p.m.

I give more info below.


permanent link
Jirong Hu (1.5k6264256) | answered Sep 01 '15, 10:42 p.m.
I started DOS command windows as Administrators and it runs. But still I can't run the job maybe for two reasons:
1. The agent is still with v7.
2. I can login with root but not the domain user, my license server is still on v7.

Microsoft Windows [Version 6.3.9600]
(c) 2013 Microsoft Corporation. All rights reserved.

C:\Windows\system32>cd D:\Program Files (x86)\IBM\Build Forge1\services-plugins

C:\Windows\system32>d:

D:\Program Files (x86)\IBM\Build Forge1\services-plugins>cd ..

D:\Program Files (x86)\IBM\Build Forge1>bfengine.exe -d
Registry Key = [LOCAL_MACHINE\SOFTWARE\BuildForge Management Console 8.0.0.2-7-0085]
Apache Installed
Tomcat Installed
Config file = [D:\Program Files (x86)\IBM\Build Forge1\buildforge.conf]
DB2 Instance [DB2]

D:\Program Files (x86)\IBM\Build Forge1>Sep 01, 2015 10:31:52 PM org.apache.catalina.core.AprLifecycleListener init
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not fou
nd on the java.library.path: D:\Program Files (x86)\IBM\Build Forge1\ibmjdk\jre\bin\default;D:\Program Files (x86)\IBM\B
uild Forge1\ibmjdk\jre\bin;C:\Windows\SysWOW64;C:\Windows;D:\Program Files (x86)\IBM\Build Forge1\apache\bin;D:\Program
Files (x86)\IBM\Build Forge1\\token_libs;C:\Program Files (x86)\IBM\SQLLIB\BIN;C:\Windows\system32;C:\Windows;C:\Windows
\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\PROGRA~2\IBM\SQLLIB\BIN;C:\PROGRA~2\IBM\SQLLIB\FUNCTION;C:
\PROGRA~2\IBM\SQLLIB\SAMPLES\REPL;.
Sep 01, 2015 10:31:52 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8080"]
Sep 01, 2015 10:31:52 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8443"]
Sep 01, 2015 10:31:54 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["ajp-bio-8009"]
Sep 01, 2015 10:31:54 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 2408 ms
Sep 01, 2015 10:31:54 PM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Sep 01, 2015 10:31:54 PM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.55
Sep 01, 2015 10:31:54 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive D:\Program Files (x86)\IBM\Build Forge1\Apache\tomcat\webapps\BuildForgeHelp.war

Sep 01, 2015 10:32:03 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive D:\Program Files (x86)\IBM\Build Forge1\Apache\tomcat\webapps\BuildForgeHelp
.war has finished in 9,265 ms
Sep 01, 2015 10:32:03 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive D:\Program Files (x86)\IBM\Build Forge1\Apache\tomcat\webapps\jas.war

osgi> Sep 01, 2015 10:32:05 PM com.ibm.jas.osgi.Activator initializeJavaUtilLogging
INFO: Logging initialized from bundleresource://7/com/buildforge/services/server/logging.properties
Sep 01, 2015 10:32:05 PM com.ibm.jas.osgi.Activator start
INFO: Activator starting HttpServiceTracker
Sep 01, 2015 10:32:05 PM com.ibm.jas.osgi.Activator$HttpServiceTracker addingService
INFO: Registered / with /web
Sep 01, 2015 10:32:05 PM com.ibm.jas.osgi.Activator$HttpServiceTracker addingService
INFO: Registered '/ServicesLayer' with SL servlet
Sep 01, 2015 10:32:05 PM com.ibm.jas.osgi.Activator$HttpServiceTracker addingService
INFO: Registered '/AuthServlet' with authentication servlet
Sep 01, 2015 10:32:06 PM com.buildforge.services.server.config.Configuration loadResourceAsProperties
INFO: Resource [/buildforge.conf] loaded from [bundleresource://7/buildforge.conf]
Sep 01, 2015 10:32:06 PM com.buildforge.services.server.Main initLogManager
INFO: Load the logging.properties from JVM logging configuration.
Sep 01, 2015 10:32:06 PM com.buildforge.services.server.Main printVersion
INFO: Could not determine version
Sep 01, 2015 10:32:06 PM com.buildforge.services.server.Main printJDK
INFO: Running on java : IBM Corporation version 1.7.0, VM : IBM Corporation IBM J9 VM version 2.6 runtime pwi3270sr8fp10
ifx-20150313_01 (SR8 FP10+IV70681) OS Name: Windows Server 2012 R2 OS Version: 6.3 OS Arch: x86 Running on Path :D:\Prog
ram Files (x86)\IBM\Build Forge1
Sep 01, 2015 10:32:06 PM com.buildforge.services.server.db.ConnectionPool initDriver
INFO: Loading driver class for database type 'DB2' driver=com.ibm.db2.jcc.DB2Driver
Sep 01, 2015 10:32:06 PM com.buildforge.services.server.db.ConnectionPool initDriver
INFO: Initialized driver for database type 'DB2' driver=com.ibm.db2.jcc.DB2Driver
Sep 01, 2015 10:32:06 PM com.buildforge.services.server.db.ConnectionPool <init>
INFO: Initializing connection pool for db2, host='cctrand01', dbname='BLFDEV', schema='BLFUSR', db_pool='120'
Sep 01, 2015 10:32:07 PM com.ibm.jas.osgi.Activator$HttpServiceTracker addingService
INFO: Registered '/LoginServlet' with login servlet
Sep 01, 2015 10:32:07 PM com.ibm.jas.osgi.Activator$HttpServiceTracker addingService
INFO: Registered '/ExitServlet' with exit servlet
Sep 01, 2015 10:32:07 PM com.ibm.jas.osgi.Activator$HttpServiceTracker addingService
INFO: Registered '/ErrorServlet' with error servlet
Sep 01, 2015 10:32:07 PM com.ibm.jas.osgi.Activator$HttpServiceTracker addingService
INFO: Registered '/RedirectServlet' with redirect servlet
Sep 01, 2015 10:32:07 PM com.ibm.jas.osgi.Activator$HttpServiceTracker addingService
INFO: Registered '/PasswordChangeServlet' with password change servlet
Sep 01, 2015 10:32:07 PM com.ibm.jas.osgi.Activator$HttpServiceTracker addingService
INFO: Registered '/ConfigServlet' with config servlet
Sep 01, 2015 10:32:07 PM com.ibm.jas.osgi.Activator start
INFO: Activator started HttpServiceTracker
Sep 01, 2015 10:32:07 PM com.ibm.jas.mjc.step.impl.MJCStepProviderActivator start
INFO: Registered Step Provider 'MJC Step' with MJC runtime
Sep 01, 2015 10:32:07 PM com.ibm.jas.mjc.step.adaptor.AdaptorStepProviderActivator start
INFO: Registered Step Provider 'Adaptor Step' with MJC runtime
Sep 01, 2015 10:32:07 PM com.ibm.jas.mjc.server.impl.AgentServerProviderActivator start
INFO: Registered Server Provider 'BF Agent' with MJC runtime
Sep 01, 2015 10:32:07 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive D:\Program Files (x86)\IBM\Build Forge1\Apache\tomcat\webapps\jas.war has fi
nished in 3,985 ms
Sep 01, 2015 10:32:07 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8080"]
Sep 01, 2015 10:32:08 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8443"]
Sep 01, 2015 10:32:08 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["ajp-bio-8009"]
Sep 01, 2015 10:32:08 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 13893 ms
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.db.PropertiesLoader load
INFO: Loaded resource file: com/buildforge/services/server/db/sql.properties
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.db.PropertiesLoader load
INFO: Loaded resource file: com/buildforge/services/server/db/db2/sql.properties
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.manager.db.StringManagerImpl refreshMessages
INFO: Loading string catalogs ...
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.manager.db.StringManagerImpl refreshMessages
INFO: Loading string catalog: cs_CZ
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.manager.db.StringManagerImpl refreshMessages
INFO: Loading string catalog: de_DE
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.manager.db.StringManagerImpl refreshMessages
INFO: Loading string catalog: en_GB
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.manager.db.StringManagerImpl refreshMessages
INFO: Loading string catalog: en_US
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.manager.db.StringManagerImpl refreshMessages
INFO: Loading string catalog: es_ES
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.manager.db.StringManagerImpl refreshMessages
INFO: Loading string catalog: fr_FR
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.manager.db.StringManagerImpl refreshMessages
INFO: Loading string catalog: hu_HU
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.manager.db.StringManagerImpl refreshMessages
INFO: Loading string catalog: it_IT
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.manager.db.StringManagerImpl refreshMessages
INFO: Loading string catalog: ja_JP
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.manager.db.StringManagerImpl refreshMessages
INFO: Loading string catalog: ko_KR
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.manager.db.StringManagerImpl refreshMessages
INFO: Loading string catalog: pl_PL
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.manager.db.StringManagerImpl refreshMessages
INFO: Loading string catalog: pt_BR
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.manager.db.StringManagerImpl refreshMessages
INFO: Loading string catalog: ru_RU
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.manager.db.StringManagerImpl refreshMessages
INFO: Loading string catalog: zh_CN
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.manager.db.StringManagerImpl refreshMessages
INFO: Loading string catalog: zh_TW
Sep 01, 2015 10:32:10 PM com.buildforge.services.server.manager.db.StringManagerImpl refreshIds
INFO: Reading message IDs ...
Sep 01, 2015 10:32:11 PM com.buildforge.services.server.Main readServicesLayerIdFile
INFO: Services Layer ID 751070ec0c721000ed09000087276159 starting up
Sep 01, 2015 10:32:11 PM com.buildforge.services.server.sched.ScheduledService start
INFO: com.buildforge.services.server.sched.AccessCachePoller: START
Sep 01, 2015 10:32:11 PM com.buildforge.services.server.sched.ScheduledService start
INFO: com.buildforge.services.server.sched.LicenseServerRefreshPoller: START
Sep 01, 2015 10:32:11 PM com.buildforge.services.server.event.EventEngine start
INFO: EventEngine starting
Sep 01, 2015 10:32:11 PM com.buildforge.services.server.plugin.PluginMonitor init
INFO: Plugin directory 'D:/Program Files (x86)/IBM/Build Forge1/services-plugins' is now being monitored
Sep 01, 2015 10:32:11 PM com.buildforge.services.server.plugin.PluginDeployer deploy
INFO: Deploying 'com.ibm.rational.connector.buildforge.services.plugin.jar' to 'D:\Program Files (x86)\IBM\Build Forge1\
services-plugins\com.ibm.rational.connector.buildforge.services.plugin'
Sep 01, 2015 10:32:11 PM com.buildforge.services.server.plugin.PluginDeployer deploy
INFO: Loading deployment descriptor for 'com.ibm.rational.connector.buildforge.services.plugin.jar'
Sep 01, 2015 10:32:11 PM com.buildforge.services.server.plugin.descriptor.PluginDescriptorFactory buildDescriptor
INFO: Loading deployment descriptor from 'D:\Program Files (x86)\IBM\Build Forge1\services-plugins\com.ibm.rational.conn
ector.buildforge.services.plugin\META-INF\bfDescriptor.xml'
Sep 01, 2015 10:32:11 PM com.buildforge.services.server.plugin.PluginDeployer deploy
INFO: Loaded deployment descriptor for 'com.ibm.rational.connector.buildforge.services.plugin.jar'
Sep 01, 2015 10:32:11 PM com.buildforge.services.server.plugin.PluginDeployer deploy
INFO: Registering plugin 'com.ibm.rational.connector.buildforge.services.plugin.jar'
Sep 01, 2015 10:32:11 PM com.buildforge.services.server.sched.Scheduler run
INFO: Initializing scheduler
Sep 01, 2015 10:32:11 PM com.buildforge.services.server.plugin.PluginDeployer registerPluginWithEventing
INFO: Deploying plugin from persisted database registrations for 'D:\Program Files (x86)\IBM\Build Forge1\services-plugi
ns\com.ibm.rational.connector.buildforge.services.plugin'
Sep 01, 2015 10:32:11 PM com.buildforge.services.server.plugin.PluginDeployer deploy
INFO: Registered plugin 'com.ibm.rational.connector.buildforge.services.plugin.jar'
Sep 01, 2015 10:32:11 PM com.buildforge.services.server.sched.ScheduledService start
INFO: com.buildforge.services.server.db.migration.BlockStoreUnhexer: START
Sep 01, 2015 10:32:11 PM com.buildforge.services.server.sched.ScheduledService start
INFO: com.buildforge.services.server.db.migration.LogClobMigrator: START
Sep 01, 2015 10:32:11 PM com.buildforge.services.server.sched.ScheduledService start
INFO: com.buildforge.services.server.sched.AccountLockoutTimer: START
Sep 01, 2015 10:32:11 PM com.buildforge.services.server.sched.ScheduledService start
INFO: com.buildforge.services.server.sched.AuditLogFlusher: START
Sep 01, 2015 10:32:11 PM com.buildforge.services.server.sched.ScheduledService start
INFO: com.buildforge.services.server.sched.BuildClassPurger: START
Sep 01, 2015 10:32:12 PM com.buildforge.services.server.sched.ScheduledService start
INFO: com.buildforge.services.server.sched.BuildCleaner: START
Sep 01, 2015 10:32:12 PM com.buildforge.services.server.sched.ScheduledService start
INFO: com.buildforge.services.server.sched.BuildEnvironmentCleaner: START
Sep 01, 2015 10:32:12 PM com.buildforge.services.server.sched.ScheduledService start
INFO: com.buildforge.services.server.sched.CronScheduler: START
Sep 01, 2015 10:32:12 PM com.buildforge.services.server.sched.ScheduledService start
INFO: com.buildforge.services.server.sched.FileStorePurger: START
Sep 01, 2015 10:32:12 PM com.buildforge.services.server.sched.ScheduledService start
INFO: com.buildforge.services.server.sched.LogLineBufferFlusher: START
Sep 01, 2015 10:32:12 PM com.buildforge.services.server.sched.ScheduledService start
INFO: com.buildforge.services.server.event.RtcEventCleaner: START
Sep 01, 2015 10:32:12 PM com.buildforge.services.server.sched.ScheduledService start
INFO: com.buildforge.services.server.sched.StringCacheRefreshPoller: START
Sep 01, 2015 10:32:12 PM com.buildforge.services.server.sched.ScheduledService start
INFO: com.buildforge.services.server.sched.TokenCleaner: START
Sep 01, 2015 10:32:12 PM com.buildforge.services.server.sched.ScheduledService start
INFO: com.ibm.jas.xmlAdaptor.init.XMLAdaptorInitializer: START
Sep 01, 2015 10:32:12 PM com.buildforge.services.server.Main processHiddenToDecode
INFO: migration decode
Sep 01, 2015 10:32:12 PM com.buildforge.services.server.Main processHiddenToDecode
INFO: already done
Sep 01, 2015 10:32:12 PM com.buildforge.services.server.Main init
INFO: Initialization complete.
Sep 01, 2015 10:32:12 PM com.buildforge.services.server.Main idle
INFO: Blocking until the dispatcher shuts down...
Sep 01, 2015 10:32:15 PM com.buildforge.services.server.util.AccessCache check
INFO: check: Initializing
Sep 01, 2015 10:32:16 PM com.buildforge.services.server.db.migration.BlockStoreUnhexer unhex
INFO: Done.
Sep 01, 2015 10:32:16 PM com.buildforge.services.server.sched.ScheduledService run
INFO: com.buildforge.services.server.db.migration.BlockStoreUnhexer: DONE
Sep 01, 2015 10:32:19 PM com.buildforge.services.server.db.migration.ReportMigrator migrate
INFO: Done.
Sep 01, 2015 10:32:19 PM com.buildforge.services.server.manager.fti.AuditLogManagerImpl getAuditPolicy
INFO: Audit policy: BASIC
Sep 01, 2015 10:32:20 PM com.buildforge.services.server.lm.rcl.RclLicenseProvider rclManager
WARNING: Feature >BuildForgeStdServer< either isn't available, or has no more seats or tokens available.
Sep 01, 2015 10:35:25 PM com.ibm.jas.agent.AgentConnection connect
WARNING: AgentConnection: saddr=[s12.hbc.com/192.168.12.1:5555]java.net.ConnectException: Connection refused: no further
 information
Sep 01, 2015 10:35:25 PM com.ibm.jas.fiber.FiberThread$1 uncaughtException
WARNING: Exception in fiber "ServicesLayer[Server Test: s12.hbc.com]": Connection refused: no further information
Sep 01, 2015 10:36:59 PM com.buildforge.services.server.sso.SsoManagerImpl authenticate
INFO: CRRBF1414I: Authenticating user 'HBC/root' for UI access.
Sep 01, 2015 10:38:12 PM com.buildforge.services.server.manager.db.BuildManagerImpl create
INFO: build stage:
Sep 01, 2015 10:39:03 PM com.buildforge.services.server.sso.SsoManagerImpl authenticate
INFO: CRRBF1414I: Authenticating user 'HBC/bldforge02' for UI access.
Sep 01, 2015 10:39:03 PM com.buildforge.services.server.api.AuthContext loginLdap
INFO: Authenticated LDAP user: bldforge02
com.ibm.rcl.rational.LicenseBadServerVersionException
There is a version mismatch between this application's FLEXNet library and the license server version.
The version of the application's FLEXNet library is 11.8.
The license server found is 27000@ccreqpwebp01 and the version is 11.5.
A supported configuration must have the server version equal or higher than the application's FLEXNet library version.
Upgrading the license server is needed.
All license files searched for features:
27000@ccreqpwebp01

        at com.ibm.rcl.rational.FLEXLicenseControl.checkoutName(Unknown Source)
        at com.ibm.rcl.rational.FLEXLicense.Out_Name(Unknown Source)
        at com.buildforge.services.server.lm.rcl.RclLicenseProvider.rclManager(RclLicenseProvider.java:844)
        at com.buildforge.services.server.lm.rcl.RclLicenseProvider.checkout(RclLicenseProvider.java:345)
        at com.buildforge.services.server.lm.rcl.RclLicenseProvider.acquire(RclLicenseProvider.java:680)
        at com.buildforge.services.server.lm.LicenseServer.acquire(LicenseServer.java:553)
        at com.buildforge.services.server.lm.LicenseServer.acquireFloatingUserLicense(LicenseServer.java:1115)
        at com.buildforge.services.server.lm.LicenseServer.adjustFloatingUserLicense(LicenseServer.java:1089)
        at com.buildforge.services.server.lm.LicenseServer.adjustUserLicense(LicenseServer.java:1020)
        at com.buildforge.services.server.api.AuthContext.login(AuthContext.java:839)
        at com.buildforge.services.server.sso.SsoManagerImpl.authenticate(SsoManagerImpl.java:372)
        at com.buildforge.services.server.web.AuthServlet.authenticate(AuthServlet.java:61)
        at com.buildforge.services.server.web.AuthServlet.doPost(AuthServlet.java:156)
        at com.buildforge.services.server.web.AuthServlet.service(AuthServlet.java:166)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:727)
        at org.eclipse.equinox.http.servlet.internal.ServletRegistration.handleRequest(ServletRegistration.java:91)
        at org.eclipse.equinox.http.servlet.internal.ProxyServlet.processAlias(ProxyServlet.java:110)
        at org.eclipse.equinox.http.servlet.internal.ProxyServlet.service(ProxyServlet.java:60)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:727)
        at org.eclipse.equinox.servletbridge.BridgeServlet.service(BridgeServlet.java:115)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:727)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
        at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
        at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:501)
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:171)
        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:103)
        at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:950)
        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408)
        at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1070)
        at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:611)
        at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:316)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1157)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:627)
        at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
        at java.lang.Thread.run(Thread.java:801)
Sep 01, 2015 10:39:05 PM com.buildforge.services.server.sso.SsoManagerImpl authenticate
WARNING: CRRBF1417I: An exception occurred authenticating user 'HBC/bldforge02'.  The message is: 'User licenses exceede
d'.
com.buildforge.services.server.lm.LicenseException: User licenses exceeded
        at com.buildforge.services.server.lm.LicenseServer.adjustFloatingUserLicense(LicenseServer.java:1105)
        at com.buildforge.services.server.lm.LicenseServer.adjustUserLicense(LicenseServer.java:1020)
        at com.buildforge.services.server.api.AuthContext.login(AuthContext.java:839)
        at com.buildforge.services.server.sso.SsoManagerImpl.authenticate(SsoManagerImpl.java:372)
        at com.buildforge.services.server.web.AuthServlet.authenticate(AuthServlet.java:61)
        at com.buildforge.services.server.web.AuthServlet.doPost(AuthServlet.java:156)
        at com.buildforge.services.server.web.AuthServlet.service(AuthServlet.java:166)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:727)
        at org.eclipse.equinox.http.servlet.internal.ServletRegistration.handleRequest(ServletRegistration.java:91)
        at org.eclipse.equinox.http.servlet.internal.ProxyServlet.processAlias(ProxyServlet.java:110)
        at org.eclipse.equinox.http.servlet.internal.ProxyServlet.service(ProxyServlet.java:60)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:727)
        at org.eclipse.equinox.servletbridge.BridgeServlet.service(BridgeServlet.java:115)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:727)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
        at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
        at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:501)
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:171)
        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:103)
        at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:950)
        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408)
        at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1070)
        at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:611)
        at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:316)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1157)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:627)
        at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
        at java.lang.Thread.run(Thread.java:801)

com.buildforge.services.server.lm.LicenseException: User licenses exceeded
        at com.buildforge.services.server.lm.LicenseServer.adjustFloatingUserLicense(LicenseServer.java:1105)
        at com.buildforge.services.server.lm.LicenseServer.adjustUserLicense(LicenseServer.java:1020)
        at com.buildforge.services.server.api.AuthContext.login(AuthContext.java:839)
        at com.buildforge.services.server.sso.SsoManagerImpl.authenticate(SsoManagerImpl.java:372)
        at com.buildforge.services.server.web.AuthServlet.authenticate(AuthServlet.java:61)
        at com.buildforge.services.server.web.AuthServlet.doPost(AuthServlet.java:156)
        at com.buildforge.services.server.web.AuthServlet.service(AuthServlet.java:166)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:727)
        at org.eclipse.equinox.http.servlet.internal.ServletRegistration.handleRequest(ServletRegistration.java:91)
        at org.eclipse.equinox.http.servlet.internal.ProxyServlet.processAlias(ProxyServlet.java:110)
        at org.eclipse.equinox.http.servlet.internal.ProxyServlet.service(ProxyServlet.java:60)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:727)
        at org.eclipse.equinox.servletbridge.BridgeServlet.service(BridgeServlet.java:115)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:727)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
        at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
        at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:501)
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:171)
        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:103)
        at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:950)
        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408)
        at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1070)
        at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:611)
        at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:316)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1157)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:627)
        at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
        at java.lang.Thread.run(Thread.java:801)


Comments
Spencer Murata commented Sep 02 '15, 8:59 a.m.
FORUM MODERATOR / JAZZ DEVELOPER

 This is different than the plugin problem.  This is a problem with the new version of BuildForge is shipped with a newer version of the license module that is not compatible with your current license server, so you will need to upgrade your license server so BF and the license server can communicate.


~Spencer


permanent link
Joshua Keefe (1011) | answered Sep 02 '15, 6:41 p.m.
So, it appears the issue may be the license server version in use.

com.ibm.rcl.rational.LicenseBadServerVersionException
There is a version mismatch between this application's FLEXNet library and the license server version.
The version of the application's FLEXNet library is 11.8.
The license server found is 27000@<license_server> and the version is 11.5.

You had noted the Rational License Server was version 7.x, but the Build Forge v8.0 support matrix indicates you must used Rational License Server version 8.0 - 8.1.4.

http://www-969.ibm.com/software/reports/compatibility/clarity-reports/report/html/softwareReqsForProduct?deliverableId=1393367638576&osPlatforms=AIX|HP|IBM%20i|Linux|Solaris|Windows|z/OS&duComponentIds=S001|A003|A002&mandatoryCapIds=9|13|42|26&optionalCapIds=30|7|36

If possible, it would be recommended to upgrade the Rational License Server when possible.

Also, please verify if you are using the Build Forge v8.0 MJC (Java-based engine) or the legacy Perl engine and if the projects have been migrated to the MJC.
If the projects have not be migrated, you will need to manually start the legacy Perl engine.
More details can be seen from the following.

Migrating legacy Perl job controller projects
http://www-01.ibm.com/support/knowledgecenter/SSB2MV_8.0.0/com.ibm.rational.buildforge.doc/topics/utilities_migrating_perl_proj.html?lang=en

Starting and stopping the Perl engine on Windows
http://www-01.ibm.com/support/knowledgecenter/SSB2MV_8.0.0/com.ibm.rational.buildforge.doc/topics/admin_engine_startstop_win.html?lang=en

Comments
Jirong Hu commented Sep 15 '15, 10:50 a.m. | edited Sep 15 '15, 11:09 a.m.

When I start the mgmt console foreground, I see this WARN message too:


Sep 15, 2015 11:05:38 AM com.buildforge.services.server.lm.rcl.RclLicenseProvider rclManager
WARNING: Feature >BuildForgeStdAuth< either isn't available, or has no more seats or tokens available.
Sep 15, 2015 11:06:30 AM com.buildforge.services.server.manager.db.BuildManagerImpl create
INFO: build stage:


Jirong Hu commented Sep 15 '15, 11:09 a.m.

 When I start the mgmt console foreground, I see this WARN message too:

Sep 15, 2015 11:05:38 AM com.buildforge.services.server.lm.rcl.RclLicenseProvider rclManager
WARNING: Feature >BuildForgeStdAuth< either isn't available, or has no more seats or tokens available.
Sep 15, 2015 11:06:30 AM com.buildforge.services.server.manager.db.BuildManagerImpl create
INFO: build stage:


permanent link
Jirong Hu (1.5k6264256) | answered Sep 15 '15, 10:49 a.m.
edited Sep 15 '15, 10:53 a.m.
We are migrating BF from v7 to v8 following this method:https://jazz.net/forum/questions/175930/how-to-migrate-buildforge-from-v713-to-the-latest-v8002. Now I've tried running "bfengine -p" at the command line. Then I create a simple new project with one echo step, but it hangs at "Status: Spawning --
Date: Not Started"



Other questions:
1. All our v7 projects are using Perl engine. Do we have to migrate them to the new Java engine?
2. How can change the project to use Perl engine as default, or started as the service? Run it from command line is not practical.

Below is the output of "bfengine -p". I notice the mgmt console service is also running. They are different?

D:\Program Files (x86)\IBM\Build Forge1>bfengine -p
Registry Key = [LOCAL_MACHINE\SOFTWARE\BuildForge Management Console 8.0.0.2-7-0
085]
Apache Installed
Tomcat Installed
Config file = [D:\Program Files (x86)\IBM\Build Forge1\buildforge.conf]
DB2 Instance [DB2]

D:\Program Files (x86)\IBM\Build Forge1>
D:\Program Files (x86)\IBM\Build Forge1>9/15/2015 10:28:45 AM: CRRBF0566I: 4824:
 Build Forge: Process [4824] started.
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [bf_plugin_dir] to [/services-plugins]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [export_handler] to [bfexport]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [db_schema] to [BLFUSR]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [services_tcp_port] to [3966]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [scrubber_handler] to [bfscrubber]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [scope_handler] to [bflink]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [birt_home] to [/birt]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [bf_perl_pw_crypt_impl] to [BuildForge::Utilities::BfCrypt]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [services_url] to [https://jxn-ms-bforgd01.hbc.com:8443/jas]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [location_name] to [normal]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [db_tcp_port] to [60032]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [install_path] to [D:/Program Files (x86)/IBM/Build Forge1]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [bf_pw_crypt_enabled] to [false]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [bf_file_storage] to [/temp]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [shutdown_handler] to [bfshutdown]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [cron_handler] to [bfsched]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [real_hostname] to [jxn-ms-bforgd01.hbc.com]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [db_provider] to [db2]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [db_username] to [BLFUSR]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [db_database] to [BLFDEV]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [gdd] to [none]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [bomexport_handler] to [bfbomexport]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [services_ssl_port] to [49150]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [services_hostname] to [jxn-ms-bforgd01.domain.com]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [db_hostname] to [cctrand01]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [stepcmd_handler] to [bfstepcmd]
9/15/2015 10:28:47 AM: CRRBF1573I: 4824: BuildForge: Setting startup config para
meter [build_handler] to [bfproject]
9/15/2015 10:28:47 AM: CRRBF0650I: 4824: Build Forge: Build Forge Engine [8.0.0.
2-7-0085] started [Tue Sep 15 10:28:47 2015].
9/15/2015 10:28:47 AM: CRRBF0959I: 4824: LicMgr: [1] user licenses validated.
9/15/2015 10:28:47 AM: CRRBF0769I: 4824: LicMgr: Concurrent user license mode is
 enabled.
9/15/2015 10:28:47 AM: CRRBF0956I: 4824: LicMgr: Unlimited project licenses vali
dated
9/15/2015 10:28:47 AM: CRRBF1994I: 4824: LicMgr: Threading is enabled.
9/15/2015 10:28:47 AM: CRRBF1540I: 4824: LicMgr: Server pooling is enabled.
9/15/2015 10:28:47 AM: CRRBF1479I: 4824: LicMgr: Source Adaptors are enabled.
9/15/2015 10:28:47 AM: CRRBF0579I: 4824: LicMgr: Defect Adaptors are enabled.
9/15/2015 10:28:47 AM: CRRBF1102I: 4824: LicMgr: Packaging Adaptors are enabled.

9/15/2015 10:28:47 AM: CRRBF1992I: 4824: LicMgr: Test Adaptors are enabled.
9/15/2015 10:28:47 AM: CRRBF0378I: 4824: LicMgr: Multiple database support is en
abled.
9/15/2015 10:28:47 AM: CRRBF0506I: 4824: LicMgr: Server clusters are disabled.
9/15/2015 10:28:47 AM: CRRBF0800I: 4824: LicMgr: Divisional license found.
9/15/2015 10:28:47 AM: CRRBF0997I: 4824: LicMgr: Multiplatform support is enable
d.
9/15/2015 10:28:47 AM: CRRBF0428I: 4824: LicMgr: BOM generation is enabled.
9/15/2015 10:28:47 AM: CRRBF1255I: 4824: LicMgr: Production mode server.
9/15/2015 10:28:47 AM: CRRBF1008I: 4824: LicMgr: Manifests are disabled.
9/15/2015 10:28:47 AM: CRRBF1349I: 4824: LicMgr: Reports are enabled.
9/15/2015 10:28:47 AM: CRRBF1564I: 4824: LicMgr: Reflectors are enabled.
9/15/2015 10:28:47 AM: CRRBF1253I: 4824: Build Forge: Process for id [be0d46f40c
721000f621b1ecae949a57] [B] died unexpectedly.
9/15/2015 10:28:51 AM: CRRBF0571I: 4824: DB: Database [BLFDEV] has reached size
of [2544156672], current threshold [5000000000].



Comments
Spencer Murata commented Sep 22 '15, 10:52 a.m.
FORUM MODERATOR / JAZZ DEVELOPER

 You don't need to upgrade your projects.  They will automatically switch to using the java engine.  You can add the perl switch to the service start to automatically start the perl engine.


~Spencer

Your answer


Register or to post your answer.