Bug 501841 - Add more logging to controllers

* Further logging for Commit controller gathering and presenting changes
* Version creation on server

Change-Id: I2b0112428bb57736d51659a3b84b7d31756237ce
Signed-off-by: Johannes Faltermeier <jfaltermeier@eclipsesource.com>

bundles/org.eclipse.emf.emfstore.client/src/org/eclipse/emf/emfstore/internal/client/model/controller/CommitController.java

@@ -136,6 +136,8 @@ private PrimaryVersionSpec commit(final String logMessage, final BranchVersionSp
 
 		getProgressMonitor().worked(10);
 		getProgressMonitor().subTask(Messages.CommitController_GatheringChanges);
+		EMFStoreClientUtil.logProjectDetails(LOGGING_PREFIX, "Gathering changes...", //$NON-NLS-1$
+			getProjectSpace(), branch, getUsersession());
 
 		final AbstractChangePackage localChangePackage = getProjectSpace().getLocalChangePackage();
 
@@ -147,7 +149,12 @@ private PrimaryVersionSpec commit(final String logMessage, final BranchVersionSp
 		final ModelElementIdToEObjectMappingImpl idToEObjectMapping = new ModelElementIdToEObjectMappingImpl(
 			getProjectSpace().getProject(), localChangePackage);
 
+		EMFStoreClientUtil.logProjectDetails(LOGGING_PREFIX, "Gathering changes... done", //$NON-NLS-1$
+			getProjectSpace(), branch, getUsersession());
+
 		getProgressMonitor().subTask(Messages.CommitController_PresentingChanges);
+		EMFStoreClientUtil.logProjectDetails(LOGGING_PREFIX, "Presenting changes...", //$NON-NLS-1$
+			getProjectSpace(), branch, getUsersession());
 		if (!callback.inspectChanges(getProjectSpace().toAPI(),
 			localChangePackage.toAPI(),
 			idToEObjectMapping.toAPI())
@@ -157,6 +164,8 @@ private PrimaryVersionSpec commit(final String logMessage, final BranchVersionSp
 
 			return getProjectSpace().getBaseVersion();
 		}
+		EMFStoreClientUtil.logProjectDetails(LOGGING_PREFIX, "Presenting changes... done", //$NON-NLS-1$
+			getProjectSpace(), branch, getUsersession());
 
 		getProgressMonitor().subTask(Messages.CommitController_SendingFilesToServer);
 		// TODO reimplement with ObserverBus and think about subtasks for commit

bundles/org.eclipse.emf.emfstore.server/src/org/eclipse/emf/emfstore/internal/server/core/subinterfaces/VersionSubInterfaceImpl.java

@@ -376,6 +376,11 @@ public PrimaryVersionSpec createVersion(SessionId sessionId, ProjectId projectId
 		final ACUser user = (ACUser) ESUserImpl.class.cast(copyAndResolveUser).toInternalAPI();
 		sanityCheckObjects(sessionId, projectId, baseVersionSpec, changePackage, logMessage);
 
+		final ProjectHistory projectHistory = getSubInterface(ProjectSubInterfaceImpl.class).getProject(projectId);
+		ModelUtil.logProjectDetails("Creating version on server...", user.getName(), projectHistory.getProjectName(), //$NON-NLS-1$
+			projectHistory.getProjectId().getId(), targetBranch != null ? targetBranch.getBranch() : null,
+			baseVersionSpec.getIdentifier());
+
 		if (FileBasedChangePackage.class.isInstance(changePackage)
 			&& !ServerConfiguration.useFileBasedChangePackageOnServer()) {
 			// File-based change package should never arrive here in production mode
@@ -386,8 +391,14 @@ public PrimaryVersionSpec createVersion(SessionId sessionId, ProjectId projectId
 			throw new ESException(Messages.VersionSubInterfaceImpl_FileBasedChangePackageExpected);
 		}
 
-		return internalCreateVersion(projectId, baseVersionSpec, changePackage, targetBranch, sourceVersion,
-			logMessage, user);
+		final PrimaryVersionSpec result = internalCreateVersion(projectId, baseVersionSpec, changePackage, targetBranch,
+			sourceVersion, logMessage, user);
+
+		ModelUtil.logProjectDetails("Creating version on server... done", user.getName(), //$NON-NLS-1$
+			projectHistory.getProjectName(), projectHistory.getProjectId().getId(), targetBranch != null ? targetBranch.getBranch() : null,
+			baseVersionSpec.getIdentifier());
+
+		return result;
 	}
 
 	private PrimaryVersionSpec internalCreateVersion(ProjectId projectId, PrimaryVersionSpec baseVersionSpec,