It's all about the answers!

Ask a question

RTC Build shows "no progress information" for Buil


Willy Dhondt (5821123) | asked Jul 19 '10, 9:49 a.m.
Hi,
I'm running Build Forge 7.1.1.4,
BuildForgeConnectorServer-7.1.1.4-0-0010.zip
RTC 2.0.0.3
on WAS 7.0.0.9
-----------------------------------
RTC "Request Build" launches Build Forge project that I defined in the RTC build.
The "Builds" status in RTC shows that the build is running (green check and arrow showing) and it
shows the correct label of the BF job that as launched.
The problem is that progress column of "Builds" shows "no progress information" and that the RTC reports that the build is running even after the Build Forge job is finished.

build_service.log shows that BuildForgeEventPollerScheduledTask runs as ADMIN although I changed this to JazzBuilder in JazzAdmin, Advanced options.

==========================
BuildForgeBuildLoopScheduledTask, Mon Jul 19 15:24:46 CEST 2010: Starting build loop run task, buildResultsMap: {da36c6320c4c1000e042756058205820:l32f3d0:30055=com.ibm.team.build.internal.common.model.impl.BuildResultImpl@64e164e1 (stateId: , itemId: , origin: <unset>, immutable: true) (contextId: , modified: 2010-07-19 15:14:31.234, workingCopy: false) (mergePredecessor: null, workingCopyPredecessor: null, workingCopyMergePredecessor: null, predecessor: ) (buildStatus: OK, buildState: IN_PROGRESS, label: JazzBuild (JazzBuild_16 as JazzUser) for willy, buildTimeTaken: -1, buildStartTime: 1279545269828, summary: , ignoreWarnings: true, tags: , deleteAllowed: true, personalBuild: false), da35915b0c4c1000e163756053695369:l32f3d0:30055=com.ibm.team.build.internal.common.model.impl.BuildResultImpl@bd10bd1 (stateId: , itemId: , origin: <unset>, immutable: true) (contextId: , modified: 2010-07-19 15:13:12.25, workingCopy: false) (mergePredecessor: null, workingCopyPredecessor: null, workingCopyMergePredecessor: null, predecessor: ) (buildStatus: OK, buildState: IN_PROGRESS, label: JazzBuild (JazzBuild_15 as JazzUser) for willy, buildTimeTaken: -1, buildStartTime: 1279545190296, summary: , ignoreWarnings: true, tags: , deleteAllowed: true, personalBuild: false)}
BuildForgeBuildLoopScheduledTask, Mon Jul 19 15:24:47 CEST 2010: response: com.ibm.team.process.internal.common.advice.impl.ItemsResponseImpl@52c252c2 (internalId: <unset>)
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:24:47 CEST 2010: RunAs User is: ADMIN
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:24:47 CEST 2010: Starting build loop run task, buildResultsMap: {da36c6320c4c1000e042756058205820:l32f3d0:30055=com.ibm.team.build.internal.common.model.impl.BuildResultImpl@64e164e1 (stateId: , itemId: , origin: <unset>, immutable: true) (contextId: , modified: 2010-07-19 15:14:31.234, workingCopy: false) (mergePredecessor: null, workingCopyPredecessor: null, workingCopyMergePredecessor: null, predecessor: ) (buildStatus: OK, buildState: IN_PROGRESS, label: JazzBuild (JazzBuild_16 as JazzUser) for willy, buildTimeTaken: -1, buildStartTime: 1279545269828, summary: , ignoreWarnings: true, tags: , deleteAllowed: true, personalBuild: false), da35915b0c4c1000e163756053695369:l32f3d0:30055=com.ibm.team.build.internal.common.model.impl.BuildResultImpl@bd10bd1 (stateId: , itemId: , origin: <unset>, immutable: true) (contextId: , modified: 2010-07-19 15:13:12.25, workingCopy: false) (mergePredecessor: null, workingCopyPredecessor: null, workingCopyMergePredecessor: null, predecessor: ) (buildStatus: OK, buildState: IN_PROGRESS, label: JazzBuild (JazzBuild_15 as JazzUser) for willy, buildTimeTaken: -1, buildStartTime: 1279545190296, summary: , ignoreWarnings: true, tags: , deleteAllowed: true, personalBuild: false)}
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:24:47 CEST 2010: jobActivitiesTable: {}
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:24:47 CEST 2010: stepActivitiesTable: {}
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:24:47 CEST 2010: serverEventCreatedTimeTable: {}
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:24:47 CEST 2010: Adding engine to list: com.ibm.team.build.internal.common.model.impl.BuildEngineImpl@78b078b0 (stateId: , itemId: , origin: <unset>, immutable: true) (contextId: , modified: 2010-07-19 14:22:52.359, workingCopy: <unset>) (mergePredecessor: null, workingCopyPredecessor: <unset>, workingCopyMergePredecessor: <unset>, predecessor: ) (supportsCancellation: false, engineContactInterval: 0, useTeamScheduler: false, id: RationalBuildForgeConnector, active: true)
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:24:47 CEST 2010: Build definition name: BuildForge
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:24:47 CEST 2010: Processing this build definition: com.ibm.team.build.internal.common.model.impl.BuildDefinitionImpl@52025202 (stateId: , itemId: , origin: <unset>, immutable: true) (contextId: , modified: 2010-07-19 14:22:43.843, workingCopy: <unset>) (mergePredecessor: null, workingCopyPredecessor: <unset>, workingCopyMergePredecessor: <unset>, predecessor: null) (id: BuildForge, description: , ignoreWarnings: true)
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:24:47 CEST 2010: Project data: project name: JazzBuild, uuid: cbc879dd0c4c10008dd47560232c232c
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:24:47 CEST 2010: Connection data: userid: JazzUser, hostname: l32f3d0, port: 30055
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:24:47 CEST 2010: Connection data: getAllLogs: false, getLogsNotPassedOrSkipped: true, getCustomBomData: true, numFirstLogs: 5, numLastLogs: 5, numFirstLogsEnabled: true, numLastLogsEnabled: true
BuildForgeDataManager, Mon Jul 19 15:24:47 CEST 2010: Connection returned from pool for key: l32f3d0:30055:JazzUser = com.buildforge.services.client.api.APIClientConnection@7c217c21
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:24:47 CEST 2010: Time last checked: 0, for definition ID: _-seFUJMvEd-gCMd2Ovl0YQ and server key: l32f3d0:30055
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:24:47 CEST 2010: RTC events found: []
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:24:47 CEST 2010: getBFEventsAndSort started.
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:24:47 CEST 2010: Sorted list of events.
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:24:47 CEST 2010: Setting time last checked: 0, for definition ID: _-seFUJMvEd-gCMd2Ovl0YQ and server key: l32f3d0:30055
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:24:47 CEST 2010: getBFEventsAndSort completed.
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:24:47 CEST 2010: []
BuildForgeBuildLoopScheduledTask, Mon Jul 19 15:25:02 CEST 2010: Starting build loop run task, buildResultsMap: {da36c6320c4c1000e042756058205820:l32f3d0:30055=com.ibm.team.build.internal.common.model.impl.BuildResultImpl@64e164e1 (stateId: , itemId: , origin: <unset>, immutable: true) (contextId: , modified: 2010-07-19 15:14:31.234, workingCopy: false) (mergePredecessor: null, workingCopyPredecessor: null, workingCopyMergePredecessor: null, predecessor: ) (buildStatus: OK, buildState: IN_PROGRESS, label: JazzBuild (JazzBuild_16 as JazzUser) for willy, buildTimeTaken: -1, buildStartTime: 1279545269828, summary: , ignoreWarnings: true, tags: , deleteAllowed: true, personalBuild: false), da35915b0c4c1000e163756053695369:l32f3d0:30055=com.ibm.team.build.internal.common.model.impl.BuildResultImpl@bd10bd1 (stateId: , itemId: , origin: <unset>, immutable: true) (contextId: , modified: 2010-07-19 15:13:12.25, workingCopy: false) (mergePredecessor: null, workingCopyPredecessor: null, workingCopyMergePredecessor: null, predecessor: ) (buildStatus: OK, buildState: IN_PROGRESS, label: JazzBuild (JazzBuild_15 as JazzUser) for willy, buildTimeTaken: -1, buildStartTime: 1279545190296, summary: , ignoreWarnings: true, tags: , deleteAllowed: true, personalBuild: false)}
BuildForgeBuildLoopScheduledTask, Mon Jul 19 15:25:02 CEST 2010: response: com.ibm.team.process.internal.common.advice.impl.ItemsResponseImpl@502b502b (internalId: <unset>)
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:25:02 CEST 2010: RunAs User is: ADMIN
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:25:02 CEST 2010: Starting build loop run task, buildResultsMap: {da36c6320c4c1000e042756058205820:l32f3d0:30055=com.ibm.team.build.internal.common.model.impl.BuildResultImpl@64e164e1 (stateId: , itemId: , origin: <unset>, immutable: true) (contextId: , modified: 2010-07-19 15:14:31.234, workingCopy: false) (mergePredecessor: null, workingCopyPredecessor: null, workingCopyMergePredecessor: null, predecessor: ) (buildStatus: OK, buildState: IN_PROGRESS, label: JazzBuild (JazzBuild_16 as JazzUser) for willy, buildTimeTaken: -1, buildStartTime: 1279545269828, summary: , ignoreWarnings: true, tags: , deleteAllowed: true, personalBuild: false), da35915b0c4c1000e163756053695369:l32f3d0:30055=com.ibm.team.build.internal.common.model.impl.BuildResultImpl@bd10bd1 (stateId: , itemId: , origin: <unset>, immutable: true) (contextId: , modified: 2010-07-19 15:13:12.25, workingCopy: false) (mergePredecessor: null, workingCopyPredecessor: null, workingCopyMergePredecessor: null, predecessor: ) (buildStatus: OK, buildState: IN_PROGRESS, label: JazzBuild (JazzBuild_15 as JazzUser) for willy, buildTimeTaken: -1, buildStartTime: 1279545190296, summary: , ignoreWarnings: true, tags: , deleteAllowed: true, personalBuild: false)}
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:25:02 CEST 2010: jobActivitiesTable: {}
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:25:02 CEST 2010: stepActivitiesTable: {}
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:25:02 CEST 2010: serverEventCreatedTimeTable: {}
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:25:02 CEST 2010: Adding engine to list: com.ibm.team.build.internal.common.model.impl.BuildEngineImpl@2dc52dc5 (stateId: , itemId: , origin: <unset>, immutable: true) (contextId: , modified: 2010-07-19 14:22:52.359, workingCopy: <unset>) (mergePredecessor: null, workingCopyPredecessor: <unset>, workingCopyMergePredecessor: <unset>, predecessor: ) (supportsCancellation: false, engineContactInterval: 0, useTeamScheduler: false, id: RationalBuildForgeConnector, active: true)
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:25:02 CEST 2010: Build definition name: BuildForge
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:25:02 CEST 2010: Processing this build definition: com.ibm.team.build.internal.common.model.impl.BuildDefinitionImpl@4a044a04 (stateId: , itemId: , origin: <unset>, immutable: true) (contextId: , modified: 2010-07-19 14:22:43.843, workingCopy: <unset>) (mergePredecessor: null, workingCopyPredecessor: <unset>, workingCopyMergePredecessor: <unset>, predecessor: null) (id: BuildForge, description: , ignoreWarnings: true)
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:25:02 CEST 2010: Project data: project name: JazzBuild, uuid: cbc879dd0c4c10008dd47560232c232c
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:25:02 CEST 2010: Connection data: userid: JazzUser, hostname: l32f3d0, port: 30055





==========================================
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:25:02 CEST 2010: Connection data: getAllLogs: false, getLogsNotPassedOrSkipped: true, getCustomBomData: true, numFirstLogs: 5, numLastLogs: 5, numFirstLogsEnabled: true, numLastLogsEnabled: true
BuildForgeDataManager, Mon Jul 19 15:25:02 CEST 2010: Connection returned from pool for key: l32f3d0:30055:JazzUser = com.buildforge.services.client.api.APIClientConnection@7c217c21
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:25:02 CEST 2010: Time last checked: 0, for definition ID: _-seFUJMvEd-gCMd2Ovl0YQ and server key: l32f3d0:30055
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:25:02 CEST 2010: RTC events found: []
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:25:02 CEST 2010: getBFEventsAndSort started.
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:25:02 CEST 2010: Sorted list of events.
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:25:02 CEST 2010: Setting time last checked: 0, for definition ID: _-seFUJMvEd-gCMd2Ovl0YQ and server key: l32f3d0:30055
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:25:02 CEST 2010: getBFEventsAndSort completed.
BuildForgeEventPollerScheduledTask, Mon Jul 19 15:25:02 CEST 2010: []

5 answers



permanent link
Peter Birk (501145) | answered Aug 02 '10, 12:21 p.m.
JAZZ DEVELOPER
There are a few reasons why this might be happening. There are two different event handlers for the plugin. The first is for launching the build, which appears to be working just fine. The second is for reading the build events from the BF table to update the build result. This one appears to be failing. Here are a couple of reasons.

1. The DB server time is too skewed compare to the BF server time. This causes the events to get created with a time which is already expired, according to the BF server. Make sure these two servers clocks are sync'd.

2. The RTC Eclipse client-side plugin you are using to configure the build definitions are old. There's a known but when using really old versions of the plugin which causes the BF server to "lose" the Project UUID as something to look at events for. What version of the Eclipse client plugin are you using? You might want to try to use the 7.1.1.4 version of the Eclipse client plugin.

3. You may not have a dedicated user for your RTC build definitions. Use of the same user ID elsewhere (even another RTC instance) will cause the session to get knocked off and cause communication problems. This typically will also be a problem even starting the build, but that sometimes goes through due to retries.

If any of these do not resolve the problem, let me know and I'd like to see more logs from BF and RTC.

Regards,
Pete

permanent link
Kelly Anakwe (6198) | answered Aug 31 '11, 9:00 a.m.
I'm experiencing a similar problem now where the build job completes on the BuildForge side but still shows as pending/overdue on the RTC Build definition build results sides.

We recently upgraded our RTC from RTC3.0 to RTC3.0.1 and are running BuildForge 7.1.2.0. I've run through the checklist provided without any luck as I'm on later versions than the OP, is there anytghing else you can suggest I should check to resolve this.

Thanks, Kelly

permanent link
Kelly Anakwe (6198) | answered Aug 31 '11, 9:38 a.m.
This is resolved now. We had an old non LDAP build connection user ID that was continually failing to authenticate in the background. The build definitions started updating once we deleted this ID.

permanent link
Vivek Pandey (1011211) | answered Oct 20 '11, 1:23 a.m.
Hi Kelly,

you deleted the id from RTC or Build Forge? Can you please detail out the steps you performed for this ?

Thanks.


This is resolved now. We had an old non LDAP build connection user ID that was continually failing to authenticate in the background. The build definitions started updating once we deleted this ID.

permanent link
Kelly Anakwe (6198) | answered Oct 21 '11, 9:21 a.m.
Hi Kelly,

you deleted the id from RTC or Build Forge? Can you please detail out the steps you performed for this ?

Thanks.


{/quote]

Hello,

Prior to our upgrade on RTC we had used a non LDAP ID as the buildforge connction user on our build engine. After the upgrade, this user ID stopped working and output errors to jazz.log every 20seconds or so as it tried to connect but failed to authenticate (user ID was fine logging in directly to Buildforge). We deleted the ID on the RTC build engine and replaced it with an LDAP one and this resolved the problem.

Your answer


Register or to post 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.