Integrate Build Forge with Quality Manager
Hi all,
I just integrated Build Forge with RTC, then tried to integrate Build Forge with RQM following the documentation at http://publib.boulder.ibm.com/infocenter/rqmhelp/v1r0m0/index.jsp?topic=/com.ibm.rational.test.lm.doc/topics/t_lm_intbf.html. However, when attempted to add a machine to Build Forge from RQM, an error occured, RQM reports below: -class com.ibm.rational.test.lm.service.spi.integration.IntegrationService:addAsset AWXSP5007E Encountered an error within the external provider SPI: com.ibm.rational.test.lm.spi.common.SPIException: BuildForge APIException: Unexpected exception: java.lang.IllegalArgumentException: Empty key When attempting to synchronize the inventory history, a similar exception was thrown as: ERROR com.ibm.rational.test.lm.service - class com.ibm.rational.test.lm.service.spi.integration.IntegrationService:getAllAssets AWXSP5007E Encountered an error within the external provider SPI: com.ibm.rational.test.lm.spi.common.SPIException: BuildForge APIException: Unexpected exception: java.lang.IllegalArgumentException: Empty key May I know what might be the possible cause? And what else can we do with the integration between Build Forge and RQM? From the link given above, only limited integrated feature was introduced. Build Forge version: 7.1.1.4 RQM version: 2.0.1 Thanks so much for your great help. Regards, Fu Yong |
4 answers
Not enough information from just this error message. The exception appears to be coming from something lower-level than BF (like the JDK). Almost looks like a Kerberos error from the JDK. Can you take a look at the catalina.log in tomcat/logs (if using Tomcat for RQM and/or BF) to see if there are any errors there that look suspicious? Any additional error log information would be very beneficial. Thanks!
Regards, Pete |
Not enough information from just this error message. The exception appears to be coming from something lower-level than BF (like the JDK). Almost looks like a Kerberos error from the JDK. Can you take a look at the catalina.log in tomcat/logs (if using Tomcat for RQM and/or BF) to see if there are any errors there that look suspicious? Any additional error log information would be very beneficial. Thanks! Hi Pete, This is the catalina.log file from RQM. I am not very sure what the "connection refused" error means. Is there anything else I can look for? Thanks. Fu Yong May 20, 2010 10:58:41 AM org.apache.catalina.core.AprLifecycleListener lifecycleEvent INFO: The Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: C:\Program Files\IBM\RQM201\server\jre\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\IBM\Infoprint Select;C:\Notes;C:\Program Files\XLView;C:\lotus\compnent;C:\Utilities;C:\Program Files\Common Files\Lenovo;C:\program files\ibm\personal communications\;C:\Program Files\IBM\Trace Facility\;C:\Program Files\Intel\WiFi\bin\;C:\Program Files\ThinkPad\ConnectUtilities;C:\PROGRA~1\IBM\SQLLIB\BIN;C:\PROGRA~1\IBM\SQLLIB\FUNCTION;C:\PROGRA~1\IBM\SQLLIB\SAMPLES\REPL May 20, 2010 10:58:42 AM org.apache.coyote.http11.Http11BaseProtocol init INFO: Initializing Coyote HTTP/1.1 on http-9080 May 20, 2010 10:58:43 AM org.apache.coyote.http11.Http11BaseProtocol init INFO: Initializing Coyote HTTP/1.1 on http-9443 May 20, 2010 10:58:43 AM org.apache.catalina.startup.Catalina load INFO: Initialization processed in 2297 ms May 20, 2010 10:58:43 AM org.apache.catalina.core.StandardService start INFO: Starting service Catalina May 20, 2010 10:58:43 AM org.apache.catalina.core.StandardEngine start INFO: Starting Servlet Engine: Apache Tomcat/5.5.28 May 20, 2010 10:58:43 AM org.apache.catalina.core.StandardHost start INFO: XML validation disabled May 20, 2010 10:58:44 AM org.apache.catalina.startup.HostConfig deployWAR INFO: Deploying web application archive help.war May 20, 2010 10:59:03 AM org.apache.catalina.startup.HostConfig deployWAR INFO: Deploying web application archive jazz.war May 20, 2010 10:59:21 AM org.apache.coyote.http11.Http11BaseProtocol start INFO: Starting Coyote HTTP/1.1 on http-9080 May 20, 2010 10:59:21 AM org.apache.coyote.http11.Http11BaseProtocol start INFO: Starting Coyote HTTP/1.1 on http-9443 May 20, 2010 10:59:21 AM org.apache.jk.common.ChannelSocket init INFO: JK: ajp13 listening on /0.0.0.0:9009 May 20, 2010 10:59:21 AM org.apache.jk.server.JkMain start INFO: Jk running ID=0 time=0/31 config=null May 20, 2010 10:59:21 AM org.apache.catalina.storeconfig.StoreLoader load INFO: Find registry server-registry.xml at classpath resource May 20, 2010 10:59:21 AM org.apache.catalina.startup.Catalina start INFO: Server startup in 37593 ms May 20, 2010 11:15:09 AM org.apache.coyote.http11.Http11BaseProtocol pause INFO: Pausing Coyote HTTP/1.1 on http-9080 May 20, 2010 11:15:09 AM org.apache.coyote.http11.Http11BaseProtocol pause INFO: Pausing Coyote HTTP/1.1 on http-9443 May 20, 2010 11:15:10 AM org.apache.catalina.core.StandardService stop INFO: Stopping service Catalina May 20, 2010 11:15:10 AM org.apache.coyote.http11.Http11BaseProtocol destroy INFO: Stopping Coyote HTTP/1.1 on http-9080 May 20, 2010 11:15:10 AM org.apache.coyote.http11.Http11BaseProtocol destroy INFO: Stopping Coyote HTTP/1.1 on http-9443 May 20, 2010 11:15:10 AM org.apache.catalina.core.AprLifecycleListener lifecycleEvent INFO: Failed shutdown of Apache Portable Runtime May 20, 2010 11:15:32 AM org.apache.catalina.startup.Catalina stopServer SEVERE: Catalina.stop: java.net.ConnectException: Connection refused: connect at java.net.PlainSocketImpl.socketConnect(Native Method) at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:391) at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:252) at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:239) at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:389) at java.net.Socket.connect(Socket.java:551) at java.net.Socket.connect(Socket.java:501) at java.net.Socket.<init>(Socket.java:409) at java.net.Socket.<init>(Socket.java:223) at org.apache.catalina.startup.Catalina.stopServer(Catalina.java:395) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:79) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:618) at org.apache.catalina.startup.Bootstrap.stopServer(Bootstrap.java:344) at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:435) May 20, 2010 4:51:14 PM org.apache.catalina.core.AprLifecycleListener lifecycleEvent INFO: The Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: C:\Program Files\IBM\RQM201\server\jre\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\IBM\Infoprint Select;C:\Notes;C:\Program Files\XLView;C:\lotus\compnent;C:\Utilities;C:\Program Files\Common Files\Lenovo;C:\program files\ibm\personal communications\;C:\Program Files\IBM\Trace Facility\;C:\Program Files\Intel\WiFi\bin\;C:\Program Files\ThinkPad\ConnectUtilities;C:\PROGRA~1\IBM\SQLLIB\BIN;C:\PROGRA~1\IBM\SQLLIB\FUNCTION;C:\PROGRA~1\IBM\SQLLIB\SAMPLES\REPL;C:\Program Files\Rational\common May 20, 2010 4:51:14 PM org.apache.coyote.http11.Http11BaseProtocol init INFO: Initializing Coyote HTTP/1.1 on http-9080 May 20, 2010 4:51:16 PM org.apache.coyote.http11.Http11BaseProtocol init INFO: Initializing Coyote HTTP/1.1 on http-9443 May 20, 2010 4:51:16 PM org.apache.catalina.startup.Catalina load INFO: Initialization processed in 2250 ms May 20, 2010 4:51:16 PM org.apache.catalina.core.StandardService start INFO: Starting service Catalina May 20, 2010 4:51:16 PM org.apache.catalina.core.StandardEngine start INFO: Starting Servlet Engine: Apache Tomcat/5.5.28 May 20, 2010 4:51:16 PM org.apache.catalina.core.StandardHost start INFO: XML validation disabled May 20, 2010 4:51:26 PM org.apache.catalina.startup.HostConfig deployWAR INFO: Deploying web application archive help.war May 20, 2010 4:51:30 PM org.apache.coyote.http11.Http11BaseProtocol start INFO: Starting Coyote HTTP/1.1 on http-9080 May 20, 2010 4:51:30 PM org.apache.coyote.http11.Http11BaseProtocol start INFO: Starting Coyote HTTP/1.1 on http-9443 May 20, 2010 4:51:30 PM org.apache.jk.common.ChannelSocket init INFO: JK: ajp13 listening on /0.0.0.0:9009 May 20, 2010 4:51:30 PM org.apache.jk.server.JkMain start INFO: Jk running ID=0 time=0/15 config=null May 20, 2010 4:51:30 PM org.apache.catalina.storeconfig.StoreLoader load INFO: Find registry server-registry.xml at classpath resource May 20, 2010 4:51:30 PM org.apache.catalina.startup.Catalina start INFO: Server startup in 14438 ms May 20, 2010 4:54:58 PM org.apache.coyote.http11.Http11BaseProtocol pause INFO: Pausing Coyote HTTP/1.1 on http-9080 May 20, 2010 4:54:58 PM org.apache.coyote.http11.Http11BaseProtocol pause INFO: Pausing Coyote HTTP/1.1 on http-9443 May 20, 2010 4:54:59 PM org.apache.catalina.core.StandardService stop INFO: Stopping service Catalina May 20, 2010 4:55:05 PM org.apache.coyote.http11.Http11BaseProtocol destroy INFO: Stopping Coyote HTTP/1.1 on http-9080 May 20, 2010 4:55:05 PM org.apache.coyote.http11.Http11BaseProtocol destroy INFO: Stopping Coyote HTTP/1.1 on http-9443 May 20, 2010 4:55:05 PM org.apache.catalina.core.AprLifecycleListener lifecycleEvent INFO: Failed shutdown of Apache Portable Runtime May 20, 2010 4:55:20 PM org.apache.catalina.core.AprLifecycleListener lifecycleEvent INFO: The Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: C:\Program Files\IBM\RQM201\server\jre\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\IBM\Infoprint Select;C:\Notes;C:\Program Files\XLView;C:\lotus\compnent;C:\Utilities;C:\Program Files\Common Files\Lenovo;C:\program files\ibm\personal communications\;C:\Program Files\IBM\Trace Facility\;C:\Program Files\Intel\WiFi\bin\;C:\Program Files\ThinkPad\ConnectUtilities;C:\PROGRA~1\IBM\SQLLIB\BIN;C:\PROGRA~1\IBM\SQLLIB\FUNCTION;C:\PROGRA~1\IBM\SQLLIB\SAMPLES\REPL;C:\Program Files\Rational\common May 20, 2010 4:55:20 PM org.apache.coyote.http11.Http11BaseProtocol init INFO: Initializing Coyote HTTP/1.1 on http-9080 May 20, 2010 4:55:21 PM org.apache.coyote.http11.Http11BaseProtocol init INFO: Initializing Coyote HTTP/1.1 on http-9443 May 20, 2010 4:55:21 PM org.apache.catalina.startup.Catalina load INFO: Initialization processed in 1750 ms May 20, 2010 4:55:22 PM org.apache.catalina.core.StandardService start INFO: Starting service Catalina May 20, 2010 4:55:22 PM org.apache.catalina.core.StandardEngine start INFO: Starting Servlet Engine: Apache Tomcat/5.5.28 May 20, 2010 4:55:22 PM org.apache.catalina.core.StandardHost start INFO: XML validation disabled May 20, 2010 4:55:35 PM org.apache.catalina.startup.HostConfig deployWAR INFO: Deploying web application archive help.war May 20, 2010 4:55:37 PM org.apache.coyote.http11.Http11BaseProtocol start INFO: Starting Coyote HTTP/1.1 on http-9080 May 20, 2010 4:55:37 PM org.apache.coyote.http11.Http11BaseProtocol start INFO: Starting Coyote HTTP/1.1 on http-9443 May 20, 2010 4:55:37 PM org.apache.jk.common.ChannelSocket init INFO: JK: ajp13 listening on /0.0.0.0:9009 May 20, 2010 4:55:37 PM org.apache.jk.server.JkMain start INFO: Jk running ID=0 time=0/15 config=null May 20, 2010 4:55:37 PM org.apache.catalina.storeconfig.StoreLoader load INFO: Find registry server-registry.xml at classpath resource May 20, 2010 4:55:37 PM org.apache.catalina.startup.Catalina start INFO: Server startup in 15703 ms May 20, 2010 5:33:02 PM org.apache.coyote.http11.Http11BaseProtocol pause INFO: Pausing Coyote HTTP/1.1 on http-9080 May 20, 2010 5:33:02 PM org.apache.coyote.http11.Http11BaseProtocol pause INFO: Pausing Coyote HTTP/1.1 on http-9443 May 20, 2010 5:33:03 PM org.apache.catalina.core.StandardService stop INFO: Stopping service Catalina May 20, 2010 5:33:06 PM org.apache.catalina.startup.Catalina stopServer SEVERE: Catalina.stop: java.net.ConnectException: Connection refused: connect at java.net.PlainSocketImpl.socketConnect(Native Method) at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:391) at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:252) at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:239) at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:389) at java.net.Socket.connect(Socket.java:551) at java.net.Socket.connect(Socket.java:501) at java.net.Socket.<init>(Socket.java:409) at java.net.Socket.<init>(Socket.java:223) at org.apache.catalina.startup.Catalina.stopServer(Catalina.java:395) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:79) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:618) at org.apache.catalina.startup.Bootstrap.stopServer(Bootstrap.java:344) at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:435) May 20, 2010 5:33:07 PM org.apache.coyote.http11.Http11BaseProtocol destroy INFO: Stopping Coyote HTTP/1.1 on http-9080 May 20, 2010 5:33:07 PM org.apache.coyote.http11.Http11BaseProtocol destroy INFO: Stopping Coyote HTTP/1.1 on http-9443 May 20, 2010 5:33:07 PM org.apache.catalina.core.AprLifecycleListener lifecycleEvent INFO: Failed shutdown of Apache Portable Runtime |
I don't believe the "connection refused" errors here are pertinent. They are occurring during a stop server and it's probably normal. I don't see anything in the log you posted that would help us debug this further. You might want to post the same question to the Quality Manager forum as they might understand better how BF is getting invoked and where to look for errors from that and better instructions for setting it all up. If you don't get a good response from that forum, we can pursue this further but might need to look at QM logs to see the real stack coming from the connection problem.
Regards, Pete |
Hello
Im facing the same problem as mentioned above. But the entries in my catalina.out seem to be different: Apr 11, 2011 2:37:16 AM com.buildforge.services.server.api.APIServerConnection process WARNING: !!! java.lang.IllegalArgumentException: Empty key at javax.crypto.spec.SecretKeySpec.<init>(Unknown Source) at com.buildforge.services.server.api.auth.ChallengeResponse.respond(ChallengeResponse.java:109) at com.buildforge.services.server.api.AuthContext.answerChallenge(AuthContext.java:593) at com.buildforge.services.server.api.commands.AuthCommands.doLoginResponse(AuthCommands.java:234) at com.buildforge.services.server.api.commands.AuthCommands.doLogin(AuthCommands.java:305) at com.buildforge.services.server.api.commands.AuthCommands.invoke(AuthCommands.java:88) at com.buildforge.services.server.api.APICommandProcessor.process(APICommandProcessor.java:262) at com.buildforge.services.server.api.APIServerConnection.handleRequest(APIServerConnection.java:165) at com.buildforge.services.server.api.APIServerConnection.process(APIServerConnection.java:123) at com.buildforge.services.server.dispatch.handler.BufferedConnection.run(BufferedConnection.java:244) at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:665) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:690) at java.lang.Thread.run(Thread.java:810) Can you plz help with this? |
Your answer
Dashboards and work items are no longer publicly available, so some links may be invalid. We now provide similar information through other means. Learn more here.