Merge pull request #1832 from Hosch250/SCLogging

Hosch250 · web-flow · commit a5f4eab642de · 2016-06-15T11:06:19.000-05:00
Add logging to SC.
diff --git a/RetailCoder.VBE/UI/SourceControl/BranchesViewViewModel.cs b/RetailCoder.VBE/UI/SourceControl/BranchesViewViewModel.cs
@@ -2,6 +2,7 @@
 using System.Collections.Generic;
 using System.Linq;
 using System.Windows.Input;
+using NLog;
 using Rubberduck.SourceControl;
 using Rubberduck.UI.Command;
 // ReSharper disable ExplicitCallerInfoArgument
@@ -10,6 +11,8 @@ namespace Rubberduck.UI.SourceControl
 {
     public class BranchesViewViewModel : ViewModelBase, IControlViewModel
     {
+        private static readonly Logger Logger = LogManager.GetCurrentClassLogger();
+
         public BranchesViewViewModel()
         {
             _newBranchCommand = new DelegateCommand(_ => CreateBranch(), _ => Provider != null);
@@ -35,13 +38,17 @@ public ISourceControlProvider Provider
             get { return _provider; }
             set
             {
+                Logger.Trace("Provider changed");
+
                 _provider = value;
                 RefreshView();
             }
         }
 
         public void RefreshView()
         {
+            Logger.Trace("Refreshing view");
+
             OnPropertyChanged("LocalBranches");
             OnPropertyChanged("PublishedBranches");
             OnPropertyChanged("UnpublishedBranches");
@@ -192,7 +199,7 @@ public bool IsNotValidBranchName
             get { return !IsValidBranchName(NewBranchName); }
         }
 
-        public bool IsValidBranchName(string name)
+        private bool IsValidBranchName(string name)
         {
             // Rules taken from https://www.kernel.org/pub/software/scm/git/docs/git-check-ref-format.html
             var isValidName = !string.IsNullOrEmpty(name) &&
@@ -285,6 +292,7 @@ private void MergeBranch()
 
         private void CreateBranchOk()
         {
+            Logger.Trace("Creating branch {0}", NewBranchName);
             try
             {
                 Provider.CreateBranch(CreateBranchSource, NewBranchName);
@@ -308,6 +316,8 @@ private void CreateBranchCancel()
 
         private void MergeBranchOk()
         {
+            Logger.Trace("Merging branch {0} into branch {1}", SourceBranch, DestinationBranch);
+
             OnLoadingComponentsStarted();
 
             try
@@ -333,6 +343,8 @@ private void MergeBranchCancel()
 
         private void DeleteBranch(bool isBranchPublished)
         {
+            Logger.Trace("Deleting {0}published branch {1}", isBranchPublished ? "" : "un",
+                isBranchPublished ? CurrentPublishedBranch : CurrentUnpublishedBranch);
             try
             {
                 Provider.DeleteBranch(isBranchPublished ? CurrentPublishedBranch : CurrentUnpublishedBranch);
@@ -355,6 +367,7 @@ private bool CanDeleteBranch(bool isBranchPublished)
 
         private void PublishBranch()
         {
+            Logger.Trace("Publishing branch {0}", CurrentUnpublishedBranch);
             try
             {
                 Provider.Publish(CurrentUnpublishedBranch);
@@ -369,6 +382,7 @@ private void PublishBranch()
 
         private void UnpublishBranch()
         {
+            Logger.Trace("Unpublishing branch {0}", CurrentPublishedBranch);
             try
             {
                 Provider.Unpublish(Provider.Branches.First(b => b.Name == CurrentPublishedBranch).TrackingName);
diff --git a/RetailCoder.VBE/UI/SourceControl/ChangesViewViewModel.cs b/RetailCoder.VBE/UI/SourceControl/ChangesViewViewModel.cs
@@ -2,6 +2,7 @@
 using System.Collections.ObjectModel;
 using System.Linq;
 using System.Windows.Input;
+using NLog;
 using Rubberduck.SourceControl;
 using Rubberduck.UI.Command;
 // ReSharper disable ExplicitCallerInfoArgument
@@ -10,6 +11,8 @@ namespace Rubberduck.UI.SourceControl
 {
     public class ChangesViewViewModel : ViewModelBase, IControlViewModel
     {
+        private static readonly Logger Logger = LogManager.GetCurrentClassLogger();
+
         public ChangesViewViewModel()
         {
             _commitCommand = new DelegateCommand(_ => Commit(), _ => !string.IsNullOrEmpty(CommitMessage) && IncludedChanges != null && IncludedChanges.Any());
@@ -48,6 +51,8 @@ public ISourceControlProvider Provider
 
         public void RefreshView()
         {
+            Logger.Trace("Refreshing view");
+
             OnPropertyChanged("CurrentBranch");
 
             IncludedChanges = Provider == null
@@ -127,6 +132,8 @@ public ObservableCollection<IFileStatusEntry> UntrackedFiles
 
         private void UndoChanges(IFileStatusEntry fileStatusEntry)
         {
+            Logger.Trace("Undoing changes to file {0}", fileStatusEntry.FilePath);
+
             try
             {
                 var localLocation = Provider.CurrentRepository.LocalLocation.EndsWith("\\")
@@ -145,6 +152,8 @@ private void UndoChanges(IFileStatusEntry fileStatusEntry)
 
         private void Commit()
         {
+            Logger.Trace("Committing");
+
             var changes = IncludedChanges.Select(c => c.FilePath).ToList();
             if (!changes.Any())
             {
@@ -158,12 +167,14 @@ private void Commit()
 
                 if (CommitAction == CommitAction.CommitAndSync)
                 {
+                    Logger.Trace("Commit and sync (pull + push)");
                     Provider.Pull();
                     Provider.Push();
                 }
 
                 if (CommitAction == CommitAction.CommitAndPush)
                 {
+                    Logger.Trace("Commit and push");
                     Provider.Push();
                 }
 
@@ -194,10 +205,12 @@ private void IncludeChanges(IFileStatusEntry fileStatusEntry)
         {
             if (UntrackedFiles.FirstOrDefault(f => f.FilePath == fileStatusEntry.FilePath) != null)
             {
+                Logger.Trace("Tracking file {0}", fileStatusEntry.FilePath);
                 Provider.AddFile(fileStatusEntry.FilePath);
             }
             else
             {
+                Logger.Trace("Removing file {0} from excluded changes", fileStatusEntry.FilePath);
                 ExcludedChanges.Remove(ExcludedChanges.FirstOrDefault(f => f.FilePath == fileStatusEntry.FilePath));
             }
 
@@ -206,6 +219,7 @@ private void IncludeChanges(IFileStatusEntry fileStatusEntry)
 
         private void ExcludeChanges(IFileStatusEntry fileStatusEntry)
         {
+            Logger.Trace("Adding file {0} to excluded changes", fileStatusEntry.FilePath);
             ExcludedChanges.Add(fileStatusEntry);
 
             RefreshView();
diff --git a/RetailCoder.VBE/UI/SourceControl/SettingsViewViewModel.cs b/RetailCoder.VBE/UI/SourceControl/SettingsViewViewModel.cs
@@ -3,13 +3,16 @@
 using System.IO;
 using System.Windows.Forms;
 using System.Windows.Input;
+using NLog;
 using Rubberduck.UI.Command;
 using Rubberduck.SourceControl;
 
 namespace Rubberduck.UI.SourceControl
 {
     public class SettingsViewViewModel : ViewModelBase, IControlViewModel, IDisposable
     {
+        private static readonly Logger Logger = LogManager.GetCurrentClassLogger();
+
         private readonly ISourceControlConfigProvider _configService;
         private readonly IFolderBrowserFactory _folderBrowserFactory;
         private readonly IOpenFileDialog _openFileDialog;
@@ -125,6 +128,8 @@ private void ShowCommandPromptExePicker()
 
         private void CancelSettingsChanges()
         {
+            Logger.Trace("Settings changes canceled");
+
             UserName = _config.UserName;
             EmailAddress = _config.EmailAddress;
             DefaultRepositoryLocation = _config.DefaultRepositoryLocation;
@@ -133,6 +138,8 @@ private void CancelSettingsChanges()
 
         private void UpdateSettings()
         {
+            Logger.Trace("Settings changes saved");
+
             _config.UserName = UserName;
             _config.EmailAddress = EmailAddress;
             _config.DefaultRepositoryLocation = DefaultRepositoryLocation;
diff --git a/RetailCoder.VBE/UI/SourceControl/SourceControlViewViewModel.cs b/RetailCoder.VBE/UI/SourceControl/SourceControlViewViewModel.cs
@@ -104,6 +104,7 @@ public SourceControlViewViewModel(
 
         public void SetTab(SourceControlTab tab)
         {
+            Logger.Trace("Setting active tab to {0}", tab);
             SelectedItem = TabItems.First(t => t.ViewModel.Tab == tab);
         }
 
@@ -116,6 +117,7 @@ public void HandleAddedComponent(VBComponent component)
                 return;
             }
 
+            Logger.Trace("Component {0} added", component.Name);
             var fileStatus = Provider.Status().SingleOrDefault(stat => stat.FilePath.Split('.')[0] == component.Name);
             if (fileStatus != null)
             {
@@ -132,6 +134,7 @@ public void HandleRemovedComponent(VBComponent component)
                 return;
             }
 
+            Logger.Trace("Component {0] removed", component.Name);
             var fileStatus = Provider.Status().SingleOrDefault(stat => stat.FilePath.Split('.')[0] == component.Name);
             if (fileStatus != null)
             {
@@ -148,6 +151,7 @@ public void HandleRenamedComponent(VBComponent component, string oldName)
                 return;
             }
 
+            Logger.Trace("Component {0} renamed to {1}", oldName, component.Name);
             var fileStatus = Provider.LastKnownStatus().SingleOrDefault(stat => stat.FilePath.Split('.')[0] == oldName);
             if (fileStatus != null)
             {
@@ -186,6 +190,8 @@ public ISourceControlProvider Provider
             get { return _provider; }
             set
             {
+                Logger.Trace("Provider changed");
+
                 _provider = value;
                 OnPropertyChanged("RepoDoesNotHaveRemoteLocation");
                 SetChildPresenterSourceControlProviders(_provider);
@@ -216,7 +222,7 @@ private void _fileSystemWatcher_Changed(object sender, FileSystemEventArgs e)
             {
                 return;
             }
-
+            
             Logger.Trace("File system watcher detected file changed");
             if (_messageBox.Show(RubberduckUI.SourceControl_ExternalModifications, RubberduckUI.SourceControlPanel_Caption,
                 MessageBoxButtons.OKCancel, MessageBoxIcon.Information, MessageBoxDefaultButton.Button1) == DialogResult.OK)
@@ -534,10 +540,13 @@ private void ViewModel_ErrorThrown(object sender, ErrorEventArgs e)
 
             if (e.InnerMessage == unauthorizedMessage)
             {
+                Logger.Trace("Requesting login");
                 DisplayLoginGrid = true;
             }
             else
             {
+                Logger.Trace("Displaying {0} with title '{1}' and message '{2}'", e.NotificationType, e.Message, e.InnerMessage);
+
                 ErrorTitle = e.Message;
                 ErrorMessage = e.InnerMessage;
 
@@ -572,6 +581,7 @@ private void InitRepo()
                     return;
                 }
 
+                Logger.Trace("Initializing repo");
                 _provider = _providerFactory.CreateProvider(_vbe.ActiveVBProject);
                 var repo = _provider.InitVBAProject(folderPicker.SelectedPath);
                 Provider = _providerFactory.CreateProvider(_vbe.ActiveVBProject, repo, _wrapperFactory);
@@ -634,6 +644,7 @@ private void OpenRepo()
                     return;
                 }
 
+                Logger.Trace("Opening existing repo");
                 var project = _vbe.ActiveVBProject;
                 var repo = new Repository(project.HelpFile, folderPicker.SelectedPath, string.Empty);
 
@@ -660,6 +671,7 @@ private void CloneRepo()
         {
             OnOpenRepoStarted();
 
+            Logger.Trace("Cloning repo");
             try
             {
                 _provider = _providerFactory.CreateProvider(_vbe.ActiveVBProject);
@@ -695,6 +707,7 @@ private void PublishRepo()
                 return;
             }
 
+            Logger.Trace("Publishing repo to remote");
             try
             {
                 Provider.AddOrigin(PublishRemotePath, Provider.CurrentBranch.Name);
@@ -735,6 +748,7 @@ private void ClosePublishRepoGrid()
 
         private void OpenCommandPrompt()
         {
+            Logger.Trace("Opening command prompt");
             try
             {
                 Process.Start(_config.CommandPromptLocation);
@@ -754,6 +768,7 @@ private void OpenRepoAssignedToProject()
                 return;
             }
 
+            Logger.Trace("Opening repo assigned to project");
             try
             {
                 OnOpenRepoStarted();
@@ -775,6 +790,8 @@ private void OpenRepoAssignedToProject()
 
         private void Refresh()
         {
+            Logger.Trace("Refreshing display");
+
             _fileSystemWatcher.EnableRaisingEvents = false;
             if (Provider == null)
             {
diff --git a/RetailCoder.VBE/UI/SourceControl/UnsyncedCommitsViewViewModel.cs b/RetailCoder.VBE/UI/SourceControl/UnsyncedCommitsViewViewModel.cs
@@ -1,13 +1,16 @@
 ﻿using System;
 using System.Collections.ObjectModel;
 using System.Windows.Input;
+using NLog;
 using Rubberduck.SourceControl;
 using Rubberduck.UI.Command;
 
 namespace Rubberduck.UI.SourceControl
 {
     public class UnsyncedCommitsViewViewModel : ViewModelBase, IControlViewModel
     {
+        private static readonly Logger Logger = LogManager.GetCurrentClassLogger();
+
         public UnsyncedCommitsViewViewModel()
         {
             _fetchCommitsCommand = new DelegateCommand(_ => FetchCommits(), _ => Provider != null);
@@ -22,6 +25,8 @@ public ISourceControlProvider Provider
             get { return _provider; }
             set
             {
+                Logger.Trace("Provider changed");
+
                 _provider = value;
                 _provider.BranchChanged += Provider_BranchChanged;
 
@@ -31,6 +36,8 @@ public ISourceControlProvider Provider
 
         public void RefreshView()
         {
+            Logger.Trace("Refreshing view");
+
             CurrentBranch = Provider.CurrentBranch.Name;
 
             IncomingCommits = new ObservableCollection<ICommit>(Provider.UnsyncedRemoteCommits);
@@ -90,6 +97,7 @@ private void FetchCommits()
         {
             try
             {
+                Logger.Trace("Fetching");
                 Provider.Fetch();
 
                 RefreshView();
@@ -104,6 +112,7 @@ private void PullCommits()
         {
             try
             {
+                Logger.Trace("Pulling");
                 Provider.Pull();
 
                 RefreshView();
@@ -118,6 +127,7 @@ private void PushCommits()
         {
             try
             {
+                Logger.Trace("Pushing");
                 Provider.Push();
 
                 RefreshView();
@@ -132,6 +142,7 @@ private void SyncCommits()
         {
             try
             {
+                Logger.Trace("Syncing (pull + push)");
                 Provider.Pull();
                 Provider.Push();
 

Original file line number	Diff line number	Diff line change
`@@ -2,6 +2,7 @@`
`2`	`2`	`using System.Collections.Generic;`
`3`	`3`	`using System.Linq;`
`4`	`4`	`using System.Windows.Input;`
	`5`	`+using NLog;`
`5`	`6`	`using Rubberduck.SourceControl;`
`6`	`7`	`using Rubberduck.UI.Command;`
`7`	`8`	`// ReSharper disable ExplicitCallerInfoArgument`
`@@ -10,6 +11,8 @@ namespace Rubberduck.UI.SourceControl`
`10`	`11`	`{`
`11`	`12`	`public class BranchesViewViewModel : ViewModelBase, IControlViewModel`
`12`	`13`	`{`
	`14`	`+ private static readonly Logger Logger = LogManager.GetCurrentClassLogger();`
	`15`	`+`
`13`	`16`	`public BranchesViewViewModel()`
`14`	`17`	`{`
`15`	`18`	`_newBranchCommand = new DelegateCommand(_ => CreateBranch(), _ => Provider != null);`
`@@ -35,13 +38,17 @@ public ISourceControlProvider Provider`
`35`	`38`	`get { return _provider; }`
`36`	`39`	`set`
`37`	`40`	`{`
	`41`	`+ Logger.Trace("Provider changed");`
	`42`	`+`
`38`	`43`	`_provider = value;`
`39`	`44`	`RefreshView();`
`40`	`45`	`}`
`41`	`46`	`}`
`42`	`47`
`43`	`48`	`public void RefreshView()`
`44`	`49`	`{`
	`50`	`+ Logger.Trace("Refreshing view");`
	`51`	`+`
`45`	`52`	`OnPropertyChanged("LocalBranches");`
`46`	`53`	`OnPropertyChanged("PublishedBranches");`
`47`	`54`	`OnPropertyChanged("UnpublishedBranches");`
`@@ -192,7 +199,7 @@ public bool IsNotValidBranchName`
`192`	`199`	`get { return !IsValidBranchName(NewBranchName); }`
`193`	`200`	`}`
`194`	`201`
`195`		`- public bool IsValidBranchName(string name)`
	`202`	`+ private bool IsValidBranchName(string name)`
`196`	`203`	`{`
`197`	`204`	`// Rules taken from https://www.kernel.org/pub/software/scm/git/docs/git-check-ref-format.html`
`198`	`205`	`var isValidName = !string.IsNullOrEmpty(name) &&`
`@@ -285,6 +292,7 @@ private void MergeBranch()`
`285`	`292`
`286`	`293`	`private void CreateBranchOk()`
`287`	`294`	`{`
	`295`	`+ Logger.Trace("Creating branch {0}", NewBranchName);`
`288`	`296`	`try`
`289`	`297`	`{`
`290`	`298`	`Provider.CreateBranch(CreateBranchSource, NewBranchName);`
`@@ -308,6 +316,8 @@ private void CreateBranchCancel()`
`308`	`316`
`309`	`317`	`private void MergeBranchOk()`
`310`	`318`	`{`
	`319`	`+ Logger.Trace("Merging branch {0} into branch {1}", SourceBranch, DestinationBranch);`
	`320`	`+`
`311`	`321`	`OnLoadingComponentsStarted();`
`312`	`322`
`313`	`323`	`try`
`@@ -333,6 +343,8 @@ private void MergeBranchCancel()`
`333`	`343`
`334`	`344`	`private void DeleteBranch(bool isBranchPublished)`
`335`	`345`	`{`
	`346`	`+ Logger.Trace("Deleting {0}published branch {1}", isBranchPublished ? "" : "un",`
	`347`	`+ isBranchPublished ? CurrentPublishedBranch : CurrentUnpublishedBranch);`
`336`	`348`	`try`
`337`	`349`	`{`
`338`	`350`	`Provider.DeleteBranch(isBranchPublished ? CurrentPublishedBranch : CurrentUnpublishedBranch);`
`@@ -355,6 +367,7 @@ private bool CanDeleteBranch(bool isBranchPublished)`
`355`	`367`
`356`	`368`	`private void PublishBranch()`
`357`	`369`	`{`
	`370`	`+ Logger.Trace("Publishing branch {0}", CurrentUnpublishedBranch);`
`358`	`371`	`try`
`359`	`372`	`{`
`360`	`373`	`Provider.Publish(CurrentUnpublishedBranch);`
`@@ -369,6 +382,7 @@ private void PublishBranch()`
`369`	`382`
`370`	`383`	`private void UnpublishBranch()`
`371`	`384`	`{`
	`385`	`+ Logger.Trace("Unpublishing branch {0}", CurrentPublishedBranch);`
`372`	`386`	`try`
`373`	`387`	`{`
`374`	`388`	`Provider.Unpublish(Provider.Branches.First(b => b.Name == CurrentPublishedBranch).TrackingName);`
Original file line number	Diff line number	Diff line change
`@@ -2,6 +2,7 @@`
`2`	`2`	`using System.Collections.ObjectModel;`
`3`	`3`	`using System.Linq;`
`4`	`4`	`using System.Windows.Input;`
	`5`	`+using NLog;`
`5`	`6`	`using Rubberduck.SourceControl;`
`6`	`7`	`using Rubberduck.UI.Command;`
`7`	`8`	`// ReSharper disable ExplicitCallerInfoArgument`
`@@ -10,6 +11,8 @@ namespace Rubberduck.UI.SourceControl`
`10`	`11`	`{`
`11`	`12`	`public class ChangesViewViewModel : ViewModelBase, IControlViewModel`
`12`	`13`	`{`
	`14`	`+ private static readonly Logger Logger = LogManager.GetCurrentClassLogger();`
	`15`	`+`
`13`	`16`	`public ChangesViewViewModel()`
`14`	`17`	`{`
`15`	`18`	`_commitCommand = new DelegateCommand(_ => Commit(), _ => !string.IsNullOrEmpty(CommitMessage) && IncludedChanges != null && IncludedChanges.Any());`
`@@ -48,6 +51,8 @@ public ISourceControlProvider Provider`
`48`	`51`
`49`	`52`	`public void RefreshView()`
`50`	`53`	`{`
	`54`	`+ Logger.Trace("Refreshing view");`
	`55`	`+`
`51`	`56`	`OnPropertyChanged("CurrentBranch");`
`52`	`57`
`53`	`58`	`IncludedChanges = Provider == null`
`@@ -127,6 +132,8 @@ public ObservableCollection<IFileStatusEntry> UntrackedFiles`
`127`	`132`
`128`	`133`	`private void UndoChanges(IFileStatusEntry fileStatusEntry)`
`129`	`134`	`{`
	`135`	`+ Logger.Trace("Undoing changes to file {0}", fileStatusEntry.FilePath);`
	`136`	`+`
`130`	`137`	`try`
`131`	`138`	`{`
`132`	`139`	`var localLocation = Provider.CurrentRepository.LocalLocation.EndsWith("\\")`
`@@ -145,6 +152,8 @@ private void UndoChanges(IFileStatusEntry fileStatusEntry)`
`145`	`152`
`146`	`153`	`private void Commit()`
`147`	`154`	`{`
	`155`	`+ Logger.Trace("Committing");`
	`156`	`+`
`148`	`157`	`var changes = IncludedChanges.Select(c => c.FilePath).ToList();`
`149`	`158`	`if (!changes.Any())`
`150`	`159`	`{`
`@@ -158,12 +167,14 @@ private void Commit()`
`158`	`167`
`159`	`168`	`if (CommitAction == CommitAction.CommitAndSync)`
`160`	`169`	`{`
	`170`	`+ Logger.Trace("Commit and sync (pull + push)");`
`161`	`171`	`Provider.Pull();`
`162`	`172`	`Provider.Push();`
`163`	`173`	`}`
`164`	`174`
`165`	`175`	`if (CommitAction == CommitAction.CommitAndPush)`
`166`	`176`	`{`
	`177`	`+ Logger.Trace("Commit and push");`
`167`	`178`	`Provider.Push();`
`168`	`179`	`}`
`169`	`180`
`@@ -194,10 +205,12 @@ private void IncludeChanges(IFileStatusEntry fileStatusEntry)`
`194`	`205`	`{`
`195`	`206`	`if (UntrackedFiles.FirstOrDefault(f => f.FilePath == fileStatusEntry.FilePath) != null)`
`196`	`207`	`{`
	`208`	`+ Logger.Trace("Tracking file {0}", fileStatusEntry.FilePath);`
`197`	`209`	`Provider.AddFile(fileStatusEntry.FilePath);`
`198`	`210`	`}`
`199`	`211`	`else`
`200`	`212`	`{`
	`213`	`+ Logger.Trace("Removing file {0} from excluded changes", fileStatusEntry.FilePath);`
`201`	`214`	`ExcludedChanges.Remove(ExcludedChanges.FirstOrDefault(f => f.FilePath == fileStatusEntry.FilePath));`
`202`	`215`	`}`
`203`	`216`
`@@ -206,6 +219,7 @@ private void IncludeChanges(IFileStatusEntry fileStatusEntry)`
`206`	`219`
`207`	`220`	`private void ExcludeChanges(IFileStatusEntry fileStatusEntry)`
`208`	`221`	`{`
	`222`	`+ Logger.Trace("Adding file {0} to excluded changes", fileStatusEntry.FilePath);`
`209`	`223`	`ExcludedChanges.Add(fileStatusEntry);`
`210`	`224`
`211`	`225`	`RefreshView();`
Original file line number	Diff line number	Diff line change
`@@ -104,6 +104,7 @@ public SourceControlViewViewModel(`
`104`	`104`
`105`	`105`	`public void SetTab(SourceControlTab tab)`
`106`	`106`	`{`
	`107`	`+ Logger.Trace("Setting active tab to {0}", tab);`
`107`	`108`	`SelectedItem = TabItems.First(t => t.ViewModel.Tab == tab);`
`108`	`109`	`}`
`109`	`110`
`@@ -116,6 +117,7 @@ public void HandleAddedComponent(VBComponent component)`
`116`	`117`	`return;`
`117`	`118`	`}`
`118`	`119`
	`120`	`+ Logger.Trace("Component {0} added", component.Name);`
`119`	`121`	`var fileStatus = Provider.Status().SingleOrDefault(stat => stat.FilePath.Split('.')[0] == component.Name);`
`120`	`122`	`if (fileStatus != null)`
`121`	`123`	`{`
`@@ -132,6 +134,7 @@ public void HandleRemovedComponent(VBComponent component)`
`132`	`134`	`return;`
`133`	`135`	`}`
`134`	`136`
	`137`	`+ Logger.Trace("Component {0] removed", component.Name);`
`135`	`138`	`var fileStatus = Provider.Status().SingleOrDefault(stat => stat.FilePath.Split('.')[0] == component.Name);`
`136`	`139`	`if (fileStatus != null)`
`137`	`140`	`{`
`@@ -148,6 +151,7 @@ public void HandleRenamedComponent(VBComponent component, string oldName)`
`148`	`151`	`return;`
`149`	`152`	`}`
`150`	`153`
	`154`	`+ Logger.Trace("Component {0} renamed to {1}", oldName, component.Name);`
`151`	`155`	`var fileStatus = Provider.LastKnownStatus().SingleOrDefault(stat => stat.FilePath.Split('.')[0] == oldName);`
`152`	`156`	`if (fileStatus != null)`
`153`	`157`	`{`
`@@ -186,6 +190,8 @@ public ISourceControlProvider Provider`
`186`	`190`	`get { return _provider; }`
`187`	`191`	`set`
`188`	`192`	`{`
	`193`	`+ Logger.Trace("Provider changed");`
	`194`	`+`
`189`	`195`	`_provider = value;`
`190`	`196`	`OnPropertyChanged("RepoDoesNotHaveRemoteLocation");`
`191`	`197`	`SetChildPresenterSourceControlProviders(_provider);`
`@@ -216,7 +222,7 @@ private void _fileSystemWatcher_Changed(object sender, FileSystemEventArgs e)`
`216`	`222`	`{`
`217`	`223`	`return;`
`218`	`224`	`}`
`219`		`-`
	`225`	`+`
`220`	`226`	`Logger.Trace("File system watcher detected file changed");`
`221`	`227`	`if (_messageBox.Show(RubberduckUI.SourceControl_ExternalModifications, RubberduckUI.SourceControlPanel_Caption,`
`222`	`228`	`MessageBoxButtons.OKCancel, MessageBoxIcon.Information, MessageBoxDefaultButton.Button1) == DialogResult.OK)`
`@@ -534,10 +540,13 @@ private void ViewModel_ErrorThrown(object sender, ErrorEventArgs e)`
`534`	`540`
`535`	`541`	`if (e.InnerMessage == unauthorizedMessage)`
`536`	`542`	`{`
	`543`	`+ Logger.Trace("Requesting login");`
`537`	`544`	`DisplayLoginGrid = true;`
`538`	`545`	`}`
`539`	`546`	`else`
`540`	`547`	`{`
	`548`	`+ Logger.Trace("Displaying {0} with title '{1}' and message '{2}'", e.NotificationType, e.Message, e.InnerMessage);`
	`549`	`+`
`541`	`550`	`ErrorTitle = e.Message;`
`542`	`551`	`ErrorMessage = e.InnerMessage;`
`543`	`552`
`@@ -572,6 +581,7 @@ private void InitRepo()`
`572`	`581`	`return;`
`573`	`582`	`}`
`574`	`583`
	`584`	`+ Logger.Trace("Initializing repo");`
`575`	`585`	`_provider = _providerFactory.CreateProvider(_vbe.ActiveVBProject);`
`576`	`586`	`var repo = _provider.InitVBAProject(folderPicker.SelectedPath);`
`577`	`587`	`Provider = _providerFactory.CreateProvider(_vbe.ActiveVBProject, repo, _wrapperFactory);`
`@@ -634,6 +644,7 @@ private void OpenRepo()`
`634`	`644`	`return;`
`635`	`645`	`}`
`636`	`646`
	`647`	`+ Logger.Trace("Opening existing repo");`
`637`	`648`	`var project = _vbe.ActiveVBProject;`
`638`	`649`	`var repo = new Repository(project.HelpFile, folderPicker.SelectedPath, string.Empty);`
`639`	`650`
`@@ -660,6 +671,7 @@ private void CloneRepo()`
`660`	`671`	`{`
`661`	`672`	`OnOpenRepoStarted();`
`662`	`673`
	`674`	`+ Logger.Trace("Cloning repo");`
`663`	`675`	`try`
`664`	`676`	`{`
`665`	`677`	`_provider = _providerFactory.CreateProvider(_vbe.ActiveVBProject);`
`@@ -695,6 +707,7 @@ private void PublishRepo()`
`695`	`707`	`return;`
`696`	`708`	`}`
`697`	`709`
	`710`	`+ Logger.Trace("Publishing repo to remote");`
`698`	`711`	`try`
`699`	`712`	`{`
`700`	`713`	`Provider.AddOrigin(PublishRemotePath, Provider.CurrentBranch.Name);`
`@@ -735,6 +748,7 @@ private void ClosePublishRepoGrid()`
`735`	`748`
`736`	`749`	`private void OpenCommandPrompt()`
`737`	`750`	`{`
	`751`	`+ Logger.Trace("Opening command prompt");`
`738`	`752`	`try`
`739`	`753`	`{`
`740`	`754`	`Process.Start(_config.CommandPromptLocation);`
`@@ -754,6 +768,7 @@ private void OpenRepoAssignedToProject()`
`754`	`768`	`return;`
`755`	`769`	`}`
`756`	`770`
	`771`	`+ Logger.Trace("Opening repo assigned to project");`
`757`	`772`	`try`
`758`	`773`	`{`
`759`	`774`	`OnOpenRepoStarted();`
`@@ -775,6 +790,8 @@ private void OpenRepoAssignedToProject()`
`775`	`790`
`776`	`791`	`private void Refresh()`
`777`	`792`	`{`
	`793`	`+ Logger.Trace("Refreshing display");`
	`794`	`+`
`778`	`795`	`_fileSystemWatcher.EnableRaisingEvents = false;`
`779`	`796`	`if (Provider == null)`
`780`	`797`	`{`
Original file line number	Diff line number	Diff line change
`@@ -1,13 +1,16 @@`
`1`	`1`	`using System;`
`2`	`2`	`using System.Collections.ObjectModel;`
`3`	`3`	`using System.Windows.Input;`
	`4`	`+using NLog;`
`4`	`5`	`using Rubberduck.SourceControl;`
`5`	`6`	`using Rubberduck.UI.Command;`
`6`	`7`
`7`	`8`	`namespace Rubberduck.UI.SourceControl`
`8`	`9`	`{`
`9`	`10`	`public class UnsyncedCommitsViewViewModel : ViewModelBase, IControlViewModel`
`10`	`11`	`{`
	`12`	`+ private static readonly Logger Logger = LogManager.GetCurrentClassLogger();`
	`13`	`+`
`11`	`14`	`public UnsyncedCommitsViewViewModel()`
`12`	`15`	`{`
`13`	`16`	`_fetchCommitsCommand = new DelegateCommand(_ => FetchCommits(), _ => Provider != null);`
`@@ -22,6 +25,8 @@ public ISourceControlProvider Provider`
`22`	`25`	`get { return _provider; }`
`23`	`26`	`set`
`24`	`27`	`{`
	`28`	`+ Logger.Trace("Provider changed");`
	`29`	`+`
`25`	`30`	`_provider = value;`
`26`	`31`	`_provider.BranchChanged += Provider_BranchChanged;`
`27`	`32`
`@@ -31,6 +36,8 @@ public ISourceControlProvider Provider`
`31`	`36`
`32`	`37`	`public void RefreshView()`
`33`	`38`	`{`
	`39`	`+ Logger.Trace("Refreshing view");`
	`40`	`+`
`34`	`41`	`CurrentBranch = Provider.CurrentBranch.Name;`
`35`	`42`
`36`	`43`	`IncomingCommits = new ObservableCollection<ICommit>(Provider.UnsyncedRemoteCommits);`
`@@ -90,6 +97,7 @@ private void FetchCommits()`
`90`	`97`	`{`
`91`	`98`	`try`
`92`	`99`	`{`
	`100`	`+ Logger.Trace("Fetching");`
`93`	`101`	`Provider.Fetch();`
`94`	`102`
`95`	`103`	`RefreshView();`
`@@ -104,6 +112,7 @@ private void PullCommits()`
`104`	`112`	`{`
`105`	`113`	`try`
`106`	`114`	`{`
	`115`	`+ Logger.Trace("Pulling");`
`107`	`116`	`Provider.Pull();`
`108`	`117`
`109`	`118`	`RefreshView();`
`@@ -118,6 +127,7 @@ private void PushCommits()`
`118`	`127`	`{`
`119`	`128`	`try`
`120`	`129`	`{`
	`130`	`+ Logger.Trace("Pushing");`
`121`	`131`	`Provider.Push();`
`122`	`132`
`123`	`133`	`RefreshView();`
`@@ -132,6 +142,7 @@ private void SyncCommits()`
`132`	`142`	`{`
`133`	`143`	`try`
`134`	`144`	`{`
	`145`	`+ Logger.Trace("Syncing (pull + push)");`
`135`	`146`	`Provider.Pull();`
`136`	`147`	`Provider.Push();`
`137`	`148`