none
MAP SQL Exception - [SmoothenTimeSeries] RRS feed

  • Question

  • I have a customer trying to run a MAP assessment of his environment. Inventory and performance monitoring completed successfully but the assessment fails.

    I could not find any search results with this exception. Any help is much appreciated!

    (NB this log is from running against a very small subset of the environment, running against the full environment generates extremely large log files)

    <2015-09-16 07:52:23.27 TID-1@DefaultLogRecordSink,I> 
       |========================================
       |
       |Logging started at 09/16/2015 07:52:19
       |    Application:     MapToolkit.exe Build 9.2.292.00 retail
       |    Platform:        Win32NT 6.1.7601.65536 Service Pack 1
       |    UI Culture:      en-US
       |    Culture:         en-GB
       |    Architecture:    64-bit
       |    OS Product Name: 
       |    Machine Type:    Physical
       |    CPU:             
       |    #CPU:            
       |    #Cores:          
       |    #Logical:        
       |    System Memory:   Unknown MB
       |    Avail Memory:    856 MB
       |    Free Disk:       247975 MB
       |
       |========================================
    <2015-09-16 07:52:23.90 TID-1@SqmSessionHelper,I> GetSession(): Started SQM session Main with AppId 101457979
    <2015-09-16 07:52:32.68 TID-12@InitializingPageViewModel,I> DoInitialization(): Checking for duplicate process.
    <2015-09-16 07:52:32.68 TID-12@InitializingPageViewModel,I> DoInitialization(): Loading extensions.
    <2015-09-16 07:52:34.71 TID-12@InitializingPageViewModel,I> DoInitialization(): Completing database management initialization.
    <2015-09-16 07:52:34.96 TID-12@InitializingPageViewModel,I> DoInitialization(): Loading metadata.
    <2015-09-16 07:52:40.20 TID-12@InitializingPageViewModel,I> DoInitialization(): Checking PowerShell version.
    <2015-09-16 07:52:40.43 TID-12@InitializingPageViewModel,I> DoInitialization(): Validating version values.
    <2015-09-16 07:52:40.58 TID-13@ServerConnectionManager,I> SQL Server connection name: (LocalDB)\MAPToolkit
    <2015-09-16 07:52:50.06 TID-13@ServerConnectionManager,I> SQL Server version info: Microsoft SQL Server 2012 - 11.0.2100.60 (X64) 
       | Feb 10 2012 19:39:15 
       | Copyright (c) Microsoft Corporation
       | Express Edition (64-bit) on Windows NT 6.1 <X64> (Build 7601: Service Pack 1)
       |
    <2015-09-16 07:52:50.73 TID-12@InitializingPageViewModel,I> DoInitialization(): Preparing database.
    <2015-09-16 07:52:53.70 TID-12@InitializingPageViewModel,I> OpenPreviouslySelectedDatabase(): Opening previously selected database. INV01
    <2015-09-16 07:52:58.33 TID-12@InitializingPageViewModel,I> DoInitialization(): Initialization complete.
    <2015-09-16 07:52:59.43 TID-11@ScuiFxTraceListener,W> <NavigationModelAdaptor.GetNode> Query for node 'msscnav://root/Windows' from provider 'msscnav://providers/definition' return no result.
    <2015-09-16 07:52:59.44 TID-11@ScuiFxTraceListener,W> <TasksPublisher.OnParentNavigationNodeAvailable|WARN> No node returned for query to location 'msscnav://root/Windows', no tasks will be displayed for this location.
    <2015-09-16 07:53:10.60 TID-14@ScuiFxTraceListener,W> <NavigationModelAdaptor.GetNode> Query for node 'msscnav://root/Windows' from provider 'msscnav://providers/definition' return no result.
    <2015-09-16 07:53:10.63 TID-14@ScuiFxTraceListener,W> <TasksPublisher.OnParentNavigationNodeAvailable|WARN> No node returned for query to location 'msscnav://root/Windows', no tasks will be displayed for this location.
    <2015-09-16 07:53:16.16 TID-1@MainMenuSupport,I> OnRefreshAssessmentCommand() - The user chose to refresh the inventory assessment.
    <2015-09-16 07:53:18.14 TID-27@TaskProcessor,I> Run: Processing task: 'Assess inventory'
    <2015-09-16 07:53:18.15 TID-27@TaskProcessor,I> StartWorkers: Starting worker thread: 'AssessInventoryWorker'
    <2015-09-16 07:53:18.15 AssessInventoryWorker@TaskProcessor,I> RunWorker: Running worker: 'AssessInventoryWorker'
    <2015-09-16 07:53:18.23 AssessInventoryWorker@AssessInventoryWorker,I> AssessmentStartedEventHandler: Assessment start event.
    <2015-09-16 07:53:20.68 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Core_Assessment].[RemoveInactiveIpAddresses]] : 2418 ms
    <2015-09-16 07:53:22.38 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Core_Assessment].[RemoveDuplicateDevices]] : 1700 ms
    <2015-09-16 07:53:22.61 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Core_Assessment].[PostInventorySetting]] : 234 ms
    <2015-09-16 07:53:22.61 AssessInventoryWorker@Analyzer,I> RunAssessments() - Elapsed time for assessment 'StoredProcAssessment': 4368 ms
    <2015-09-16 07:53:22.63 AssessInventoryWorker@Analyzer,I> RunAssessments() - Elapsed time for assessment 'SetAssessmentFlagsAssessment': 31 ms
    <2015-09-16 07:53:57.39 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Win_Assessment].[WindowsInstalledSoftwareAssessment]] : 34725 ms
    <2015-09-16 07:54:07.04 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Win_Assessment].[DeviceAssessmentProc]] : 9641 ms
    <2015-09-16 07:54:07.20 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[AllDevices_Assessment].[PrepareAssessment]] : 172 ms
    <2015-09-16 07:54:13.44 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Win_Assessment].[CategorizeInventoriedDevices]] : 6224 ms
    <2015-09-16 07:54:13.49 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Unix_Assessment].[CategorizeInventoriedDevices]] : 47 ms
    <2015-09-16 07:54:29.10 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Win_Assessment].[CategorizeDiscoveredDevices]] : 15616 ms
    <2015-09-16 07:54:29.19 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Unix_Assessment].[CategorizeDiscoveredDevices]] : 93 ms
    <2015-09-16 07:54:30.18 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Win_Assessment].[CalculateHardwareInventoryCore]] : 983 ms
    <2015-09-16 07:54:30.27 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Unix_Assessment].[CalculateHardwareInventoryCore]] : 94 ms
    <2015-09-16 07:54:30.41 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[AllDevices_Assessment].[DiscoveryHardwareInventoryCore]] : 140 ms
    <2015-09-16 07:54:30.43 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[UT_FEP_Assessment].[AssessmentUsageTrackerForefrontEndpointProtection]] : 31 ms
    <2015-09-16 07:54:32.80 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Win_Assessment].[HardwareAssessmentProc]] : 2356 ms
    <2015-09-16 07:54:32.81 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Unix_Assessment].[UnixDataConsistencyAssessment]] : 16 ms
    <2015-09-16 07:54:35.77 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[VMware_Assessment].[AssessmentVMwareData]] : 2964 ms
    <2015-09-16 07:54:37.66 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[AllVirt_Assessment].[AssessmentHostGuestRelationships]] : 1887 ms
    <2015-09-16 07:54:37.72 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Win_Assessment].[CategorizeDiscoveredDevices]] : 63 ms
    <2015-09-16 07:54:37.73 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Unix_Assessment].[CategorizeDiscoveredDevices]] : 0 ms
    <2015-09-16 07:54:38.30 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Win_Assessment].[CalculateHardwareInventoryCore]] : 577 ms
    <2015-09-16 07:54:38.31 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Unix_Assessment].[CalculateHardwareInventoryCore]] : 16 ms
    <2015-09-16 07:54:38.39 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[AllDevices_Assessment].[DiscoveryHardwareInventoryCore]] : 62 ms
    <2015-09-16 07:54:47.71 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[AllDevices_Assessment].[HardwareInventoryProc]] : 9329 ms
    <2015-09-16 07:54:48.65 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[WinClient_Assessment].[LicensingAssessmentProc]] : 936 ms
    <2015-09-16 07:54:53.27 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Office_Assessment].[Office2010AssessmentProc]] : 4633 ms
    <2015-09-16 07:54:54.80 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Perf_Assessment].[AssessmentAggregatePerfData]] : 1513 ms
    <2015-09-16 07:54:55.29 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[WinServer_Assessment].[ServerAssessmentProc]] : 499 ms
    <2015-09-16 07:54:59.70 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Office_Assessment].[Office2010AssessmentProc]] : 4415 ms
    <2015-09-16 07:55:04.09 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[WinClient_Assessment].[Windows7AssessmentProc]] : 4384 ms
    <2015-09-16 07:55:06.21 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[WinClient_Assessment].[Windows8AssessmentProc]] : 2122 ms
    <2015-09-16 07:55:06.74 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[WinServer_Assessment].[Ws2012R2AssessmentProc]] : 530 ms
    <2015-09-16 07:55:07.12 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[WinServer_Assessment].[Ws2012AssessmentProc]] : 390 ms
    <2015-09-16 07:55:09.27 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[VDI_Assessment].[AssessmentWindowsThinPC]] : 2137 ms
    <2015-09-16 07:55:09.70 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[WinServer_Assessment].[Ws2008r2AssessmentProc]] : 421 ms
    <2015-09-16 07:55:12.65 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[WinServer_Assessment].[ServerRoleAssessmentProc]] : 2964 ms
    <2015-09-16 07:55:12.75 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[IE_Assessment].[AssessmentIeDiscovery]] : 94 ms
    <2015-09-16 07:55:25.81 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[SqlServer_Assessment].[AssessmentSqlServer]] : 13057 ms
    <2015-09-16 07:56:09.34 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Office_Assessment].[Office365AssessmentProc]] : 43525 ms
    <2015-09-16 07:56:09.96 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[UT_Exchange_Assessment].[AssessmentUsageTrackerExchange]] : 624 ms
    <2015-09-16 07:56:10.09 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[AzureMigration_Assessment].[PerformAzureVMQualification]] : 140 ms
    <2015-09-16 07:56:10.09 AssessInventoryWorker@Analyzer,I> RunAssessments() - Elapsed time for assessment 'StoredProcAssessment': 167452 ms
    <2015-09-16 07:56:15.24 AssessInventoryWorker@Analyzer,I> RunAssessments() - Elapsed time for assessment 'SpecIntApproxAssessment': 5132 ms
    <2015-09-16 07:56:15.24 AssessInventoryWorker@Analyzer,I> RunAssessments() - Elapsed time for assessment 'SetAssessmentFlagsAssessment': 16 ms
    <2015-09-16 07:56:15.25 AssessInventoryWorker@Analyzer,I> RunAssessments() - Elapsed time for assessment 'SetAssessmentFlagsAssessment': 0 ms
    <2015-09-16 07:56:15.37 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Perf_Assessment].[ClearPerfdata]] : 109 ms
    <2015-09-16 07:56:16.25 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Perf_Assessment].[CreateTimeIntervals]] : 873 ms
    <2015-09-16 07:58:08.77 AssessInventoryWorker@StoredProcAssessment,I> RunAssessment() - [Perf] [[Perf_Assessment].[CreateMetricsPerTimeInterval]] : 112524 ms
    <2015-09-16 08:28:09.22 AssessInventoryWorker@DataAccessCore,W> DoWorkInTransaction<T>() - Caught a SQL transaction timeout exception. Will retry 3 more time(s). Retrying in 5000 milliseconds.
       |onSqlExceptionMessage = Caught SqlException running the stored procedure [Perf_Assessment].[SmoothenTimeSeries].
       |Exception message = Timeout expired.  The timeout period elapsed prior to completion of the operation or the server is not responding.
       |The statement has been terminated.
    <2015-09-16 08:58:14.31 AssessInventoryWorker@DataAccessCore,W> DoWorkInTransaction<T>() - Caught a SQL transaction timeout exception. Will retry 2 more time(s). Retrying in 10000 milliseconds.
       |onSqlExceptionMessage = Caught SqlException running the stored procedure [Perf_Assessment].[SmoothenTimeSeries].
       |Exception message = Timeout expired.  The timeout period elapsed prior to completion of the operation or the server is not responding.
       |The statement has been terminated.
    <2015-09-16 09:28:24.40 AssessInventoryWorker@DataAccessCore,W> DoWorkInTransaction<T>() - Caught a SQL transaction timeout exception. Will retry 1 more time(s). Retrying in 15000 milliseconds.
       |onSqlExceptionMessage = Caught SqlException running the stored procedure [Perf_Assessment].[SmoothenTimeSeries].
       |Exception message = Timeout expired.  The timeout period elapsed prior to completion of the operation or the server is not responding.
       |The statement has been terminated.
    <2015-09-16 09:58:39.72 AssessInventoryWorker@Analyzer,E> RunAssessments() - Assessment threw an exception:
       |Microsoft.AssessmentPlatform.MapException: Caught SqlException running the stored procedure [Perf_Assessment].[SmoothenTimeSeries]. ---> System.Data.SqlClient.SqlException: Timeout expired.  The timeout period elapsed prior to completion of the operation or the server is not responding.
       |The statement has been terminated. ---> System.ComponentModel.Win32Exception: The wait operation timed out
       |   --- End of inner exception stack trace ---
       |   at System.Data.SqlClient.SqlConnection.OnError(SqlException exception, Boolean breakConnection, Action`1 wrapCloseInAction)
       |   at System.Data.SqlClient.TdsParser.ThrowExceptionAndWarning(TdsParserStateObject stateObj, Boolean callerHasConnectionLock, Boolean asyncClose)
       |   at System.Data.SqlClient.TdsParser.TryRun(RunBehavior runBehavior, SqlCommand cmdHandler, SqlDataReader dataStream, BulkCopySimpleResultSet bulkCopyHandler, TdsParserStateObject stateObj, Boolean& dataReady)
       |   at System.Data.SqlClient.SqlCommand.FinishExecuteReader(SqlDataReader ds, RunBehavior runBehavior, String resetOptionsString)
       |   at System.Data.SqlClient.SqlCommand.RunExecuteReaderTds(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream, Boolean async, Int32 timeout, Task& task, Boolean asyncWrite, SqlDataReader ds)
       |   at System.Data.SqlClient.SqlCommand.RunExecuteReader(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream, String method, TaskCompletionSource`1 completion, Int32 timeout, Task& task, Boolean asyncWrite)
       |   at System.Data.SqlClient.SqlCommand.InternalExecuteNonQuery(TaskCompletionSource`1 completion, String methodName, Boolean sendToPipe, Int32 timeout, Boolean asyncWrite)
       |   at System.Data.SqlClient.SqlCommand.ExecuteNonQuery()
       |   at Microsoft.AssessmentPlatform.DataAccess.DataAccessCore.ExecuteStoredProcHelper(String storedProcName, Object[] parameters)
       |   at Microsoft.AssessmentPlatform.DataAccess.DataAccessCore.DoWorkInTransaction[T](Nullable`1 isolationLevel, String onSqlExceptionMessage, TransactionWorkCallback`1 callback)
       |   --- End of inner exception stack trace ---
       |   at Microsoft.AssessmentPlatform.DataAccess.DataAccessCore.DoWorkInTransaction[T](Nullable`1 isolationLevel, String onSqlExceptionMessage, TransactionWorkCallback`1 callback)
       |   at Microsoft.AssessmentPlatform.Analytics.Assessments.StoredProcAssessment.RunAssessment(ThreadSafeFlag continueProcessingFlag, SecureString connectionString)
       |   at Microsoft.AssessmentPlatform.Analytics.Analyzer.RunAssessments(IEnumerable`1 assessments)
    <2015-09-16 09:58:39.72 AssessInventoryWorker@AssessInventoryWorker,I> AssessmentCompletedEventHandler: Assessment completed event.
    <2015-09-16 09:58:39.73 AssessInventoryWorker@TaskProcessor,I> WorkerCompleted: Worker: 'AssessInventoryWorker'
    <2015-09-16 09:58:39.73 TID-27@TaskProcessor,I> Run: Completed. Status: Failed
    <2015-09-16 09:58:41.27 TID-27@ProgressPage,I> ProgressPage.TaskCompletedCallback: sender: Microsoft.AssessmentPlatform.UI.WpfConsole.Tasks.TaskProcessor
    <2015-09-16 11:03:39.97 TID-1@ProgressPage,I> FormClosingHandler: Closing reason: UserClosing


    Thursday, September 17, 2015 11:06 AM

All replies