64243_debug.log

22:43:40.516 - Publishing message of type AutoTest.Messages.InformationMessage
22:43:40.528 - Initial configuration finished
22:43:40.563 - Checking license
22:43:40.668 - Setting up cache
22:43:40.686 - C:\Dev\Scratch\ClassLibrary1\ClassLibrary1\ClassLibrary1.csproj initialize cache
22:43:40.693 - C:\Dev\Scratch\ClassLibrary1\ClassLibrary1\ClassLibrary1.csproj preparing cache
22:43:40.720 - Trying to get path Class1.cs relative to C:\Dev\Scratch\ClassLibrary1\ClassLibrary1
22:43:40.726 - Trying to get path Properties\AssemblyInfo.cs relative to C:\Dev\Scratch\ClassLibrary1\ClassLibrary1
22:43:40.728 - Trying to get path packages.config relative to C:\Dev\Scratch\ClassLibrary1\ClassLibrary1
22:43:40.733 - Publishing message of type AutoTest.Messages.InformationMessage
22:43:40.738 - Cache initialized
22:43:40.755 - Presenter received information message
22:43:40.765 - Trying to get path .gitignore relative to C:\Dev\Scratch\ClassLibrary1
22:43:40.785 - handling information Cache initialized
22:43:40.795 - Publishing message of type AutoTest.Messages.InformationMessage
22:43:40.804 - Presenter received information message
22:43:40.805 - handling information Loading local config file C:\Users\James\AppData\Local\MightyMoose\storage\Configuration\ClassLibrary1_2d1feab7504733e5e6be58af0bdcbe29\AutoTest.config
22:43:40.830 - Trying to get path .gitignore relative to C:\Dev\Scratch\ClassLibrary1
22:43:40.832 - Using ignore file C:\Dev\Scratch\ClassLibrary1\.gitignore
22:43:40.836 - Publishing message of type AutoTest.Messages.InformationMessage
22:43:40.838 - Presenter received information message
22:43:40.842 - handling information Tracker type: file change tracking
22:43:40.892 - Watching C:\Dev\Scratch\ClassLibrary1, IsWatchingSolution = True, UseLowestCommonDenominatorAsWatchPath = True
22:43:40.894 - Publishing message of type AutoTest.Messages.InformationMessage
22:43:40.898 - Presenter received information message
22:43:40.950 - Publishing message of type AutoTest.Messages.InformationMessage
22:43:40.961 - handling information Starting AutoTest.Net and watching "C:\Dev\Scratch\ClassLibrary1" and all subdirectories.
22:43:40.963 - Publishing message of type AutoTest.Messages.InformationMessage
22:43:40.995 - Presenter received information message
22:43:41.006 - Minimizing PreProc Created
22:43:41.012 - Presenter received information message
22:43:41.014 - handling information MbUnit tests: The MbUnit runner needs a reference to your gallio bin directory to be able to run tests. Edit TestRunners\MbUnit\mbunit.config to point to the correct path.
22:43:41.016 - handling information Ignore patterns: 
22:43:41.027 - Locating assemblies for preprocessor
22:43:41.028 - Assembly build configuration
22:43:41.038 - 	Assembly not present: C:\Dev\Scratch\ClassLibrary1\ClassLibrary1\bin\AutoTest.Net\ClassLibrary1.dll
22:43:41.042 - minimizer isdebug = 
22:43:41.082 - Publishing message of type AutoTest.Messages.InformationMessage
22:43:41.084 - VM listening on 127.0.0.1:64243
22:43:41.086 - Presenter received information message
22:43:41.087 - handling information VM listening on 127.0.0.1:64243
22:43:41.089 - Starting timer
22:43:41.095 - About to send VMInitializedMessage for 127.0.0.1:64243
22:43:41.475 - Client connected to 127.0.0.1:64243 (number of connected clients 1)
22:43:41.478 - Client connected stopping timer
22:43:41.480 - Stopping timer
22:43:41.484 - Client connected to 127.0.0.1:64243 (number of connected clients 2)
22:43:41.486 - Client connected stopping timer
22:43:41.522 - Publishing message of type AutoTest.Messages.InformationMessage
22:43:41.523 - Publishing message of type AutoTest.Messages.InformationMessage
22:43:41.525 - Presenter received information message
22:43:41.531 - Presenter received information message
22:43:41.539 - handling information MbUnit tests: The MbUnit runner needs a reference to your gallio bin directory to be able to run tests. Edit TestRunners\MbUnit\mbunit.config to point to the correct path.
22:43:41.545 - handling information Ignore patterns: 
22:43:42.021 - received A message of type AutoTest.VM.Messages.RequestRiskMetricsMessage
22:43:42.023 - Building metrics for: System.Int32 ClassLibrary1.MathResult::get_Value()
22:43:42.029 - received A message of type AutoTest.VM.Messages.RequestMessage`2[AutoTest.VM.Messages.IsSolutionInitializedRequest,AutoTest.VM.Messages.IsSolutionInitializedResponse]
22:43:42.031 - Assembly path for project requested
22:43:42.033 - Getting Risk Metric for System.Int32 ClassLibrary1.MathResult::get_Value()
22:43:42.037 - received A message of type AutoTest.VM.Messages.RequestRiskMetricsMessage
22:43:42.047 - Preparing existence check for project: C:\Dev\Scratch\ClassLibrary1\ClassLibrary1\ClassLibrary1.csproj
22:43:42.049 - Checking for the existence of C:\Dev\Scratch\ClassLibrary1\ClassLibrary1\bin\AutoTest.Net\ClassLibrary1.mm.dll
22:43:42.052 - Generating risk from minimizer for 'System.Int32 ClassLibrary1.MathResult::get_Value()'
22:43:42.057 - received A message of type AutoTest.VM.Messages.RequestRiskMetricsMessage
22:43:42.064 - Building metrics for: ClassLibrary1.MathResult ClassLibrary1.ProfessorMonkeyBoy::AddsSomeNumbers(System.Int32,System.Int32)
22:43:42.066 - Getting Risk Metric for ClassLibrary1.MathResult ClassLibrary1.ProfessorMonkeyBoy::AddsSomeNumbers(System.Int32,System.Int32)
22:43:42.070 - Building metrics for: Simple.Testing.ClientFramework.Specification ClassLibrary1.Class1::When_I_enter_two_numbers_they_are_added()
22:43:42.074 - Getting Risk Metric for Simple.Testing.ClientFramework.Specification ClassLibrary1.Class1::When_I_enter_two_numbers_they_are_added()
22:43:42.077 - Generating risk from minimizer for 'ClassLibrary1.MathResult ClassLibrary1.ProfessorMonkeyBoy::AddsSomeNumbers(System.Int32,System.Int32)'
22:43:42.079 - Generating risk from minimizer for 'Simple.Testing.ClientFramework.Specification ClassLibrary1.Class1::When_I_enter_two_numbers_they_are_added()'
22:43:42.793 - graph generated with 1 nodes 0 connections.
22:43:42.796 - graph generated with 1 nodes 0 connections.
22:43:42.800 - graph generated with 1 nodes 0 connections.
22:43:42.803 - node count = 1 tests = 0
22:43:42.805 - node count = 1 tests = 0
22:43:42.813 - node count = 1 tests = 0
22:43:42.816 - counts. 0 called NaN
22:43:42.818 - counts. 0 called NaN
22:43:42.820 - Returning metrics for: System.Int32 ClassLibrary1.MathResult::get_Value()
22:43:42.821 - counts. 0 called NaN
22:43:42.823 - Returning metrics for: Simple.Testing.ClientFramework.Specification ClassLibrary1.Class1::When_I_enter_two_numbers_they_are_added()
22:43:42.826 - Returning metrics for: ClassLibrary1.MathResult ClassLibrary1.ProfessorMonkeyBoy::AddsSomeNumbers(System.Int32,System.Int32)
22:43:47.149 - received A message of type AutoTest.VM.Messages.ManualMinimizationRequestMessage
22:43:47.154 - Manual minimization requested
22:43:51.961 - received A message of type AutoTest.VM.Messages.ForceFullRunMessage
22:43:51.965 - Forcing full test run
22:43:51.973 - Publishing message of type AutoTest.Messages.ProjectChangeMessage
22:43:51.984 - Consuming project changes for:
    C:\Dev\Scratch\ClassLibrary1\ClassLibrary1\ClassLibrary1.csproj

22:43:51.990 - Publishing message of type AutoTest.Messages.RunStartedMessage
22:43:51.994 - Presenter received run start message
22:43:51.998 - handling run started
22:43:52.001 - Starting project change run
22:43:52.036 - Collection loose ends to prevent locked files before build runs
22:43:52.045 - Publishing message of type AutoTest.Messages.InformationMessage
22:43:52.047 - Running builds
22:43:52.051 - Presenter received information message
22:43:52.052 - handling information Running builds
22:43:52.060 - Publishing message of type AutoTest.Messages.RunInformationMessage
22:43:52.070 - Publishing message of type AutoTest.Messages.InformationMessage
22:43:52.072 - Running build: C:\Windows\Microsoft.NET\Framework\v4.0.30319\MSBuild.exe "C:\Dev\Scratch\ClassLibrary1\ClassLibrary1.sln" /property:OutDir=bin\AutoTest.Net\;IntermediateOutputPath=bin\AutoTest.Net\_tmpIntermediateAT\ /target:rebuild
22:43:52.074 - Presenter received information message
22:43:52.077 - handling information Running build: C:\Windows\Microsoft.NET\Framework\v4.0.30319\MSBuild.exe "C:\Dev\Scratch\ClassLibrary1\ClassLibrary1.sln" /property:OutDir=bin\AutoTest.Net\;IntermediateOutputPath=bin\AutoTest.Net\_tmpIntermediateAT\ /target:rebuild
22:43:52.079 - Presenter received run information message
22:43:52.091 - handling run information message
22:43:52.247 - Publishing message of type AutoTest.Messages.BuildRunMessage
22:43:52.254 - Presenter received build message
22:43:52.257 - Merging build run results
22:43:52.279 - Run minimized is False
22:43:52.301 - handling build message
22:43:52.304 - Locating assemblies for preprocessor
22:43:52.306 - Assembly build configuration
22:43:52.309 - 	Assembly not present: C:\Dev\Scratch\ClassLibrary1\ClassLibrary1\bin\AutoTest.Net\ClassLibrary1.dll
22:43:52.384 - Preparing runner AutoTest.Core.TestRunners.TestRunners.NUnitTestRunner
22:43:52.393 - Preparing runner AutoTest.Core.TestRunners.TestRunners.MSTestRunner
22:43:52.396 - Preparing runner AutoTest.Core.TestRunners.TestRunners.XUnitTestRunner
22:43:52.400 - Preparing runner AutoTest.Core.TestRunners.TestRunners.MSpecTestRunner
22:43:52.403 - Preparing runner AutoTest.Core.TestRunners.TestRunners.AutoTestTestRunner
22:43:52.407 - Publishing message of type AutoTest.Messages.RunFinishedMessage
22:43:52.410 - Presenter received run finished message
22:43:52.412 - handling run finished
22:43:52.419 - Consuming run finished message for change tracker

AutoTest.Client.log

Connecting to Visual Studio
Binding up Visual Studio events
Visual Studio events bound
Adding menu item
Atempting solution open
in exec of toolbox
Starting information toolbox
Information toolbox started
Spawning virtual machine for C:\Dev\Scratch\ClassLibrary1\ClassLibrary1.sln
Sending VMSpawnMessage with 004ba5dc-6d38-445e-be99-74b61aaf1496 and C:\Dev\Scratch\ClassLibrary1\ClassLibrary1.sln
Connectiong to 127.0.0.1:9070
Standalone vm creator started
Starting vm: C:\Program Files (x86)\ContinuousTests\AutoTest.VM.exe 0 "C:\Dev\Scratch\ClassLibrary1\ClassLibrary1.sln" "5c8c6ba8-feb1-4444-8e13-90e9170602a2" "normal" "64242" "local"
Creating a new RiskTagger for C:\Dev\Scratch\ClassLibrary1\ClassLibrary1\Class1.cs
Creating code cache item for c:\dev\scratch\classlibrary1\classlibrary1\class1.cs
updating code cache for c:\dev\scratch\classlibrary1\classlibrary1\class1.cs
Getting project item for c:\dev\scratch\classlibrary1\classlibrary1\class1.cs
Adding tag to Simple.Testing.ClientFramework.Specification ClassLibrary1.Class1::When_I_enter_two_numbers_they_are_added() at 11
Found glyph factory of RiskGlyph
Found glyph factory of VsTextMarker
Found glyph factory of PinnedTipGlyphFactoryProvider
Enqueuing request for risk metric of Simple.Testing.ClientFramework.Specification ClassLibrary1.Class1::When_I_enter_two_numbers_they_are_added(). Not yet connected.
Adding tag to Simple.Testing.ClientFramework.Specification ClassLibrary1.Class1::When_I_enter_two_numbers_they_are_added() at 11
Adding tag to ClassLibrary1.MathResult ClassLibrary1.ProfessorMonkeyBoy::AddsSomeNumbers(System.Int32,System.Int32) at 28
Enqueuing request for risk metric of ClassLibrary1.MathResult ClassLibrary1.ProfessorMonkeyBoy::AddsSomeNumbers(System.Int32,System.Int32). Not yet connected.
Adding tag to System.Int32 ClassLibrary1.MathResult::get_Value() at 36
Enqueuing request for risk metric of System.Int32 ClassLibrary1.MathResult::get_Value(). Not yet connected.
Adding tag to Simple.Testing.ClientFramework.Specification ClassLibrary1.Class1::When_I_enter_two_numbers_they_are_added() at 11
Adding tag to ClassLibrary1.MathResult ClassLibrary1.ProfessorMonkeyBoy::AddsSomeNumbers(System.Int32,System.Int32) at 28
Adding tag to System.Int32 ClassLibrary1.MathResult::get_Value() at 36
Adding tag to System.Int32 ClassLibrary1.MathResult::get_Value() at 36
Adding tag to Simple.Testing.ClientFramework.Specification ClassLibrary1.Class1::When_I_enter_two_numbers_they_are_added() at 11
Adding tag to ClassLibrary1.MathResult ClassLibrary1.ProfessorMonkeyBoy::AddsSomeNumbers(System.Int32,System.Int32) at 28
Adding tag to System.Int32 ClassLibrary1.MathResult::get_Value() at 36
Adding tag to System.Int32 ClassLibrary1.MathResult::get_Value() at 36
Adding tag to System.Int32 ClassLibrary1.MathResult::get_Value() at 36
Adding tag to Simple.Testing.ClientFramework.Specification ClassLibrary1.Class1::When_I_enter_two_numbers_they_are_added() at 11
Adding tag to ClassLibrary1.MathResult ClassLibrary1.ProfessorMonkeyBoy::AddsSomeNumbers(System.Int32,System.Int32) at 28
Adding tag to System.Int32 ClassLibrary1.MathResult::get_Value() at 36
Line change detected
Line change detected
Line change detected
Client conneected to standalone vm creator
Client disconnected to standalone vm creator
Recieved message of type AutoTest.Client.Config.MMConfiguration
Handling AutoTest.Client.Config.MMConfiguration
Recieved VMSpawnedMessage
Line change detected
Client conneected to standalone vm creator
Sending request for risk metric of System.Int32 ClassLibrary1.MathResult::get_Value()
Sending request for risk metric of ClassLibrary1.MathResult ClassLibrary1.ProfessorMonkeyBoy::AddsSomeNumbers(System.Int32,System.Int32)
Sending request for risk metric of Simple.Testing.ClientFramework.Specification ClassLibrary1.Class1::When_I_enter_two_numbers_they_are_added()
Risk: Message Found of AutoTest.Messages.InformationMessage
Risk: Message Found of AutoTest.Messages.InformationMessage
Risk: Message Found of AutoTest.VM.Messages.RiskMetricGeneratedMessage
Received risk metric from backend for ClassLibrary1.MathResult ClassLibrary1.ProfessorMonkeyBoy::AddsSomeNumbers(System.Int32,System.Int32) count = 1
updating ClassLibrary1.MathResult ClassLibrary1.ProfessorMonkeyBoy::AddsSomeNumbers(System.Int32,System.Int32) count =1 time = NaN called count = 0
Risk: Message Found of AutoTest.VM.Messages.RiskMetricGeneratedMessage
Risk: Message Found of AutoTest.VM.Messages.RiskMetricGeneratedMessage
Recieved message of type AutoTest.VM.Messages.IsSolutionInitializedResponse
Risk: Message Found of AutoTest.VM.Messages.IsSolutionInitializedResponse
Received risk metric from backend for Simple.Testing.ClientFramework.Specification ClassLibrary1.Class1::When_I_enter_two_numbers_they_are_added() count = 1
updating Simple.Testing.ClientFramework.Specification ClassLibrary1.Class1::When_I_enter_two_numbers_they_are_added() count =1 time = NaN called count = 0
Received risk metric from backend for System.Int32 ClassLibrary1.MathResult::get_Value() count = 1
Handling AutoTest.VM.Messages.IsSolutionInitializedResponse
Recieved message of type AutoTest.VM.Messages.RiskMetricGeneratedMessage
Handling AutoTest.VM.Messages.RiskMetricGeneratedMessage
Recieved message of type AutoTest.VM.Messages.RiskMetricGeneratedMessage
Handling AutoTest.VM.Messages.RiskMetricGeneratedMessage
updating System.Int32 ClassLibrary1.MathResult::get_Value() count =1 time = NaN called count = 0
Recieved message of type AutoTest.VM.Messages.RiskMetricGeneratedMessage
Handling AutoTest.VM.Messages.RiskMetricGeneratedMessage
Recieved message of type AutoTest.Messages.InformationMessage
Handling AutoTest.Messages.InformationMessage
Recieved message of type AutoTest.Messages.InformationMessage
Handling AutoTest.Messages.InformationMessage
Line change detected
Adding tag to ClassLibrary1.MathResult ClassLibrary1.ProfessorMonkeyBoy::AddsSomeNumbers(System.Int32,System.Int32) at 28
Adding tag to System.Int32 ClassLibrary1.MathResult::get_Value() at 36
Adding tag to System.Int32 ClassLibrary1.MathResult::get_Value() at 36
Adding tag to ClassLibrary1.MathResult ClassLibrary1.ProfessorMonkeyBoy::AddsSomeNumbers(System.Int32,System.Int32) at 28
Line change detected
Line change detected
Handling AutoTest.Messages.CacheMessages
Line change detected
Line change detected
Adding tag to ClassLibrary1.MathResult ClassLibrary1.ProfessorMonkeyBoy::AddsSomeNumbers(System.Int32,System.Int32) at 28
Adding tag to ClassLibrary1.MathResult ClassLibrary1.ProfessorMonkeyBoy::AddsSomeNumbers(System.Int32,System.Int32) at 28
client running = True
soution opened = True
client running = True
soution opened = True
client running = True
soution opened = True
client running = True
soution opened = True
Line change detected
Building all projects and running all tests
Risk: Message Found of AutoTest.Messages.RunStartedMessage
Recieved message of type AutoTest.Messages.RunStartedMessage
Handling AutoTest.Messages.RunStartedMessage
Risk: Message Found of AutoTest.Messages.InformationMessage
Recieved message of type AutoTest.Messages.InformationMessage
Handling AutoTest.Messages.InformationMessage
Recieved message of type AutoTest.Messages.InformationMessage
Risk: Message Found of AutoTest.Messages.InformationMessage
Handling AutoTest.Messages.InformationMessage
Risk: Message Found of AutoTest.Messages.RunInformationMessage
Recieved message of type AutoTest.Messages.RunInformationMessage
Handling AutoTest.Messages.RunInformationMessage
Recieved message of type AutoTest.Messages.CacheMessages
Handling AutoTest.Messages.CacheMessages
Risk: Message Found of AutoTest.Messages.CacheMessages
Risk: Message Found of AutoTest.Messages.BuildRunMessage
Recieved message of type AutoTest.Messages.BuildRunMessage
Handling AutoTest.Messages.BuildRunMessage
Recieved message of type AutoTest.Messages.RunFinishedMessage
Risk: Message Found of AutoTest.Messages.RunFinishedMessage
Handling AutoTest.Messages.RunFinishedMessage
Line change detected
Adding tag to ClassLibrary1.MathResult ClassLibrary1.ProfessorMonkeyBoy::AddsSomeNumbers(System.Int32,System.Int32) at 28
Adding tag to ClassLibrary1.MathResult ClassLibrary1.ProfessorMonkeyBoy::AddsSomeNumbers(System.Int32,System.Int32) at 28