You’re offline. This is a read only version of the page.
Hi,
I have a customer who has an issue with the tablet and mobile access to CRM2016.
The IFD configuration works with PCs but not with tablets or smartphones. I have checked the custumer's configuration ADFS and IFD and it's correct. I think the issue caused by TMG but i don't know enough this product. Is TMG is compatible with CRM2016 ?
Here is a log of connection with smartphone:
2016-08-23 12:32:51 +0000 [INFO]: App Version Number | 3.1.0.62
2016-08-23 12:32:51 +0000 [INFO]: App Short Version | 8.3
2016-08-23 12:32:51 +0000 [INFO]: | appLaunchWithArgs:
2016-08-23 12:32:51 +0000 [INFO]: Application launched
2016-08-23 12:32:51 +0000 [INFO]: | appResume
2016-08-23 12:32:51 +0000 [INFO]: CrmViewController: Application resume
2016-08-23 12:32:51 +0000 [INFO]: | viewDidLoad
2016-08-23 12:32:51 +0000 [VERBOSE]: Setting IsFirstLoad | 1
2016-08-23 12:32:51 +0000 [VERBOSE]: Start event | InitBridge
2016-08-23 12:32:51 +0000 [VERBOSE]: Setting Username | (null)
2016-08-23 12:32:51 +0000 [INFO]: End event | InitBridge, 2.245009
2016-08-23 12:32:51 +0000 [INFO]: Mark event (end without start) | ShellLaunch
2016-08-23 12:32:51 +0000 [VERBOSE]: Setting Username | (null)
2016-08-23 12:32:51 +0000 [INFO]: CrmViewController.showLoginOrErrorView: pushViewController=UserLoginScreenViewController animated=NO
2016-08-23 12:32:52 +0000 [INFO]: User given URL | https://crm2016.xxxxxx.com
2016-08-23 12:32:52 +0000 [INFO]: Constructed server URL | https://crm2016.xxxxxx.com
2016-08-23 12:32:52 +0000 [INFO]: CrmViewController: viewWillAppear
2016-08-23 12:32:52 +0000 [INFO]: CrmViewController: Navigating browser to the app URL
2016-08-23 12:32:52 +0000 [INFO]: CrmViewController: validateAndLoadApp: Account is not configured; sending HEAD request to: | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83
2016-08-23 12:32:53 +0000 [INFO]: CrmViewController: validateAndLoadApp: HEAD request completed with | HTTP status code 200 and isValidServer 1
2016-08-23 12:32:53 +0000 [INFO]: | loadURL:
2016-08-23 12:32:53 +0000 [INFO]: Loading url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83
2016-08-23 12:32:53 +0000 [VERBOSE]: Setting IsFirstLoad | 0
2016-08-23 12:32:53 +0000 [VERBOSE]: Setting ServerVersion | 8100000359
2016-08-23 12:32:53 +0000 [DEBUG]: in BackgroundTask
2016-08-23 12:32:53 +0000 [INFO]: | setupPALInfrastructure
2016-08-23 12:32:53 +0000 [VERBOSE]: Start event | PageLoad
2016-08-23 12:32:53 +0000 [INFO]: | setPALSupportCookieForURL:
2016-08-23 12:32:53 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType:
2016-08-23 12:32:53 +0000 [INFO]: Use WebView to load the request.
2016-08-23 12:32:53 +0000 [VERBOSE]: WVCD: Requested Url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=true
2016-08-23 12:32:53 +0000 [INFO]: WebScriptInputHandler: Load started
2016-08-23 12:32:53 +0000 [INFO]: WebScriptBridge: App preloading
2016-08-23 12:32:53 +0000 [VERBOSE]: Start event | DispatchersAppLoad
2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {
}
2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {
}
2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {
}
2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {
}
2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {
}
2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {
}
2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {
}
2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {
}
2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {
}
2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {
}
2016-08-23 12:32:53 +0000 [INFO]: End event | DispatchersAppLoad, 16.600013
2016-08-23 12:32:53 +0000 [INFO]: | webViewDidStartLoad:
2016-08-23 12:32:53 +0000 [INFO]: CrmViewController: webpage load started
2016-08-23 12:32:53 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType:
2016-08-23 12:32:53 +0000 [INFO]: Use WebView to load the request.
2016-08-23 12:32:53 +0000 [VERBOSE]: WVCD: Requested Url | about:blank
2016-08-23 12:32:53 +0000 [INFO]: WebScriptInputHandler: Load started
2016-08-23 12:32:53 +0000 [INFO]: WebScriptInputHandler: Page already preloaded, not triggering preload again
2016-08-23 12:32:53 +0000 [INFO]: | webViewDidStartLoad:
2016-08-23 12:32:53 +0000 [INFO]: CrmViewController: webpage load started
2016-08-23 12:32:53 +0000 [VERBOSE]: WVCD: Loaded Url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=true
2016-08-23 12:32:53 +0000 [INFO]: Load finished
2016-08-23 12:32:53 +0000 [INFO]: End event | PageLoad, 758.005023
2016-08-23 12:32:53 +0000 [INFO]: Mark event (end without start) | AppLaunch
2016-08-23 12:32:53 +0000 [INFO]: WebScriptBridge: App loading
2016-08-23 12:32:53 +0000 [VERBOSE]: Start event | DeviceReady
2016-08-23 12:32:53 +0000 [INFO]: WebScriptOutputHandler: Device ready
2016-08-23 12:32:53 +0000 [INFO]: Memory Diagnostics: Area (null); Event Device Ready; Memory used by app, total memory used by kernel, total free memory | 99.4, 1725.3, 32.2
2016-08-23 12:32:53 +0000 [INFO]: End event | DeviceReady, 2.382994
2016-08-23 12:32:53 +0000 [VERBOSE]: Start event | DispatchersAppActivate
2016-08-23 12:32:53 +0000 [INFO]: End event | DispatchersAppActivate, 0.082970
2016-08-23 12:32:53 +0000 [INFO]: | webViewDidFinishLoad:
2016-08-23 12:32:53 +0000 [INFO]: CrmViewController: webpage load finished
2016-08-23 12:32:53 +0000 [VERBOSE]: WVCD: Loaded Url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=true
2016-08-23 12:32:53 +0000 [INFO]: Load finished
2016-08-23 12:32:53 +0000 [INFO]: Mark event (end without start) | PageLoad
2016-08-23 12:32:53 +0000 [INFO]: Mark event (end without start) | AppLaunch
2016-08-23 12:32:53 +0000 [INFO]: | webViewDidFinishLoad:
2016-08-23 12:32:53 +0000 [INFO]: CrmViewController: webpage load finished
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.050008
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | logMessage, {
level = NSNumber;
message = NSString;
version = NSString;
}, "(
)", "(
version,
message,
level
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | logPerformanceData, {
data = NSString;
namespace = NSString;
version = NSString;
}, "(
)", "(
version,
namespace,
data
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:54 +0000 [INFO]: [PAL] | AppCache - AppCache exists : true
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.018001
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:54 +0000 [INFO]: [PAL] | AppCache - Manifest specified : true
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.015020
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:54 +0000 [INFO]: [PAL] | AppCache - Start Update
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.014007
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:54 +0000 [INFO]: [PAL] | Pending Log messages...
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.017047
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:54 +0000 [INFO]: [PAL] | Received appcache event...
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.018001
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:54 +0000 [INFO]: [PAL] | AppCache - Event : checking Status : Checking Details : {"isTrusted":true}
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.020981
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th0";
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th0
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | executeSqlBatch, {
callContext = DispatcherCallContext;
parameterBatch = NSArray;
statementBatch = NSArray;
version = NSString;
}, "(
onBatchComplete
)", "(
version,
callContext,
statementBatch,
parameterBatch,
onBatchComplete
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.019968
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:54 +0000 [INFO]: [PAL] | Received appcache event...
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.018001
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:54 +0000 [INFO]: [PAL] | AppCache - Event : noupdate Status : Idle Details : {"isTrusted":true}
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 21.890998
2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 4
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.015974
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:54 +0000 [INFO]: [PAL] | Setting clientStatusFlag : AppCacheUpdateCompleted to true
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.018001
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th1";
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th1
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.141978
2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.018954
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th2";
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th2
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.055969
2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.020981
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th3";
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th3
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.186026
2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.020027
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th4";
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th4
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.169992
2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.022054
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th5";
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th5
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.164986
2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.019014
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th6";
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th6
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.150979
2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.016987
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th7";
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th7
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.135005
2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.020981
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th8";
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th8
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.171959
2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.019968
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th9";
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th9
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.116944
2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.018954
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th10";
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th10
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.101030
2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.020981
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th11";
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th11
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.102997
2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.018954
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th12";
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th12
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.104010
2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.027001
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
onStringsResult = "getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB13";
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <ApplicationDispatcher: 0x17027c700>
2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onStringsResult, getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB13
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | getBoolValue, {
valueName = NSString;
version = NSString;
}, "(
onBoolResult
)", "(
version,
valueName,
onBoolResult
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | setBoolValue, {
value = NSNumber;
valueName = NSString;
version = NSString;
}, "(
)", "(
version,
valueName,
value
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | getStringValueBundle, {
valueNames = NSArray;
version = NSString;
}, "(
onStringsResult
)", "(
version,
valueNames,
onStringsResult
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | getMemoryUsage, {
version = NSString;
}, "(
onFloatResult
)", "(
version,
onFloatResult
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | captureMemoryUsage, {
area = NSString;
eventName = NSString;
version = NSString;
}, "(
onFloatResult
)", "(
version,
area,
eventName,
onFloatResult
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | changeMemoryDiagnosticsEnableState, {
memoryDiagnosticsEnabled = NSNumber;
version = NSString;
}, "(
)", "(
version,
memoryDiagnosticsEnabled
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | clientReady, {
clientState = NSDictionary;
version = NSString;
}, "(
)", "(
version,
clientState
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | clientInitialized, {
version = NSString;
}, "(
)", "(
version
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | clientBootError, {
deviceErrorAction = NSNumber;
errorDetails = NSDictionary;
version = NSString;
}, "(
)", "(
version,
deviceErrorAction,
errorDetails
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | setClientSettingsPackage, {
value = NSDictionary;
valueName = NSString;
version = NSString;
}, "(
)", "(
version,
valueName,
value
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | getStringValueBundle
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.018001
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
onStringsResult = "getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB14";
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <ApplicationDispatcher: 0x17027c700>
2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onStringsResult, getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB14
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | getStringValueBundle
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.021994
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:54 +0000 [INFO]: [PAL] | Authentication: Starting
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.016987
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
onRequestSecurityTokenResponse = "requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u815";
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <AccountDispatcher: 0x170463540>
2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onRequestSecurityTokenResponse, requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u815
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | requestSecurityToken, {
refreshToken = NSNumber;
version = NSString;
}, "(
onRequestSecurityTokenResponse
)", "(
version,
refreshToken,
onRequestSecurityTokenResponse
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | signOut, {
version = NSString;
}, "(
)", "(
version
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | reconfigure, {
version = NSString;
}, "(
)", "(
version
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | cancelSignIn, {
version = NSString;
}, "(
)", "(
version
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | authValidationError, {
deviceErrorAction = NSNumber;
errorDetails = NSDictionary;
version = NSString;
}, "(
)", "(
version,
deviceErrorAction,
errorDetails
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | setSessionID, {
sessionID = NSString;
telemetryEndPointUrl = NSString;
version = NSString;
}, "(
)", "(
version,
sessionID,
telemetryEndPointUrl
)"
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | requestSecurityToken
2016-08-23 12:32:54 +0000 [VERBOSE]: KeyChainQuery | {
acct = AuthToken;
class = genp;
"m_Limit" = "m_LimitOne";
pdmn = ck;
"r_Data" = 1;
svce = "Microsoft Office";
}
2016-08-23 12:32:54 +0000 [WARNING]: KeyChainItem operation failed. | Operaton 'CopyToken' failed with 'ItemNotFound'. Error code:-25300
2016-08-23 12:32:54 +0000 [ERROR]: Telemetry Reporter: Telemetry EndPoint not set.
2016-08-23 12:32:54 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Refreshing token"}}]
2016-08-23 12:32:54 +0000 [INFO]: Authentication: Refreshing token
2016-08-23 12:32:54 +0000 [VERBOSE]: Unregistering callback | requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u815
2016-08-23 12:32:54 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Received Data in bearer token challenge"}}]
2016-08-23 12:32:54 +0000 [INFO]: Authentication: Received Data in bearer token challenge
2016-08-23 12:32:54 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: InitializeViewController: Token error"}}]
2016-08-23 12:32:54 +0000 [ERROR]: Authentication: InitializeViewController: Token error
2016-08-23 12:32:54 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Authentication Failed."}}]
2016-08-23 12:32:54 +0000 [INFO]: Authentication: Authentication Failed.
2016-08-23 12:32:54 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Authentication Interrupted"}}]
2016-08-23 12:32:54 +0000 [ERROR]: Authentication: Authentication Interrupted
2016-08-23 12:32:54 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Auth Completed."}}]
2016-08-23 12:32:54 +0000 [INFO]: Authentication: Auth Completed.
2016-08-23 12:32:54 +0000 [INFO]: CrmViewController: Refresh
2016-08-23 12:32:54 +0000 [INFO]: CrmViewController: validateAndLoadApp: Account is not configured; sending HEAD request to: | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.020027
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
onRequestSecurityTokenResponse = "requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u816";
}
2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <AccountDispatcher: 0x170463540>
2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onRequestSecurityTokenResponse, requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u816
2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | requestSecurityToken
2016-08-23 12:32:54 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Refreshing token"}}]
2016-08-23 12:32:54 +0000 [INFO]: Authentication: Refreshing token
2016-08-23 12:32:54 +0000 [VERBOSE]: Unregistering callback | requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u816
2016-08-23 12:32:54 +0000 [INFO]: CrmViewController: validateAndLoadApp: HEAD request completed with | HTTP status code 200 and isValidServer 1
2016-08-23 12:32:54 +0000 [INFO]: | loadURL:
2016-08-23 12:32:54 +0000 [INFO]: Loading url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83
2016-08-23 12:32:54 +0000 [VERBOSE]: Setting ServerVersion | 8100000359
2016-08-23 12:32:54 +0000 [DEBUG]: in BackgroundTask
2016-08-23 12:32:54 +0000 [INFO]: | setupPALInfrastructure
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | PageLoad
2016-08-23 12:32:54 +0000 [INFO]: | setPALSupportCookieForURL:
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.035048
2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {
onRequestSecurityTokenResponse = "requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u817";
}
2016-08-23 12:32:54 +0000 [WARNING]: Dispatch: Bridge not ready
2016-08-23 12:32:54 +0000 [WARNING]: ScriptInput: Call method failed | 1
2016-08-23 12:32:54 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType:
2016-08-23 12:32:54 +0000 [INFO]: Use WebView to load the request.
2016-08-23 12:32:54 +0000 [VERBOSE]: WVCD: Requested Url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false
2016-08-23 12:32:54 +0000 [INFO]: WebScriptInputHandler: Load started
2016-08-23 12:32:54 +0000 [INFO]: WebScriptInputHandler: Page already preloaded, not triggering preload again
2016-08-23 12:32:54 +0000 [INFO]: | webViewDidStartLoad:
2016-08-23 12:32:54 +0000 [INFO]: CrmViewController: webpage load started
2016-08-23 12:32:54 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType:
2016-08-23 12:32:54 +0000 [INFO]: Use WebView to load the request.
2016-08-23 12:32:54 +0000 [VERBOSE]: WVCD: Requested Url | about:blank
2016-08-23 12:32:54 +0000 [INFO]: WebScriptInputHandler: Load started
2016-08-23 12:32:54 +0000 [INFO]: WebScriptInputHandler: Page already preloaded, not triggering preload again
2016-08-23 12:32:54 +0000 [INFO]: | webViewDidStartLoad:
2016-08-23 12:32:54 +0000 [INFO]: CrmViewController: webpage load started
2016-08-23 12:32:54 +0000 [VERBOSE]: WVCD: Loaded Url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false
2016-08-23 12:32:54 +0000 [INFO]: Load finished
2016-08-23 12:32:54 +0000 [INFO]: End event | PageLoad, 197.615027
2016-08-23 12:32:54 +0000 [INFO]: Mark event (end without start) | AppLaunch
2016-08-23 12:32:54 +0000 [INFO]: WebScriptBridge: App loading
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | DeviceReady
2016-08-23 12:32:54 +0000 [INFO]: WebScriptOutputHandler: Device ready
2016-08-23 12:32:54 +0000 [INFO]: Memory Diagnostics: Area (null); Event Device Ready; Memory used by app, total memory used by kernel, total free memory | 136.3, 1705.6, 36.4
2016-08-23 12:32:54 +0000 [INFO]: End event | DeviceReady, 0.882983
2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | DispatchersAppActivate
2016-08-23 12:32:54 +0000 [INFO]: End event | DispatchersAppActivate, 0.032008
2016-08-23 12:32:54 +0000 [INFO]: | webViewDidFinishLoad:
2016-08-23 12:32:54 +0000 [INFO]: CrmViewController: webpage load finished
2016-08-23 12:32:54 +0000 [VERBOSE]: WVCD: Loaded Url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false
2016-08-23 12:32:54 +0000 [INFO]: Load finished
2016-08-23 12:32:54 +0000 [INFO]: Mark event (end without start) | PageLoad
2016-08-23 12:32:54 +0000 [INFO]: Mark event (end without start) | AppLaunch
2016-08-23 12:32:54 +0000 [INFO]: | webViewDidFinishLoad:
2016-08-23 12:32:54 +0000 [INFO]: CrmViewController: webpage load finished
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.021994
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:55 +0000 [INFO]: [PAL] | AppCache - AppCache exists : true
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.015974
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:55 +0000 [INFO]: [PAL] | AppCache - Manifest specified : true
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.015020
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:55 +0000 [INFO]: [PAL] | AppCache - Start Update
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:55 +0000 [INFO]: [PAL] | Pending Log messages...
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.021994
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th0";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th0
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.651002
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 4
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018001
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th1";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th1
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.106990
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018001
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th2";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th2
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.047982
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.020027
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th3";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th3
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.109017
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th4";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th4
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.105023
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.020027
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th5";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th5
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.104964
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th6";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th6
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.108004
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018954
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th7";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th7
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.096977
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.020027
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th8";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th8
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.194967
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th9";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th9
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.115991
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.020027
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th10";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th10
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.099957
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018954
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th11";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th11
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.104010
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018954
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th12";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th12
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.106990
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.025034
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onStringsResult = "getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB13";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <ApplicationDispatcher: 0x17027c700>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onStringsResult, getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB13
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | getStringValueBundle
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.014961
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onStringsResult = "getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB14";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <ApplicationDispatcher: 0x17027c700>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onStringsResult, getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB14
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | getStringValueBundle
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.021994
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:55 +0000 [INFO]: [PAL] | Authentication: Starting
2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Received Data in bearer token challenge"}}]
2016-08-23 12:32:55 +0000 [INFO]: Authentication: Received Data in bearer token challenge
2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: InitializeViewController: Token error"}}]
2016-08-23 12:32:55 +0000 [ERROR]: Authentication: InitializeViewController: Token error
2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Authentication Failed."}}]
2016-08-23 12:32:55 +0000 [INFO]: Authentication: Authentication Failed.
2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Authentication Interrupted"}}]
2016-08-23 12:32:55 +0000 [ERROR]: Authentication: Authentication Interrupted
2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Auth Completed."}}]
2016-08-23 12:32:55 +0000 [INFO]: Authentication: Auth Completed.
2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: Refresh
2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: validateAndLoadApp: Account is not configured; sending HEAD request to: | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018954
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onRequestSecurityTokenResponse = "requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u815";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <AccountDispatcher: 0x170463540>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onRequestSecurityTokenResponse, requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u815
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | requestSecurityToken
2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Refreshing token"}}]
2016-08-23 12:32:55 +0000 [INFO]: Authentication: Refreshing token
2016-08-23 12:32:55 +0000 [VERBOSE]: Unregistering callback | requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u815
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018001
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:55 +0000 [INFO]: [PAL] | Received appcache event...
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.016987
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:55 +0000 [INFO]: [PAL] | AppCache - Event : noupdate Status : Idle Details : {"isTrusted":true}
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.017047
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:55 +0000 [INFO]: [PAL] | Setting clientStatusFlag : AppCacheUpdateCompleted to true
2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: validateAndLoadApp: HEAD request completed with | HTTP status code 200 and isValidServer 1
2016-08-23 12:32:55 +0000 [INFO]: | loadURL:
2016-08-23 12:32:55 +0000 [INFO]: Loading url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83
2016-08-23 12:32:55 +0000 [VERBOSE]: Setting ServerVersion | 8100000359
2016-08-23 12:32:55 +0000 [DEBUG]: in BackgroundTask
2016-08-23 12:32:55 +0000 [INFO]: | setupPALInfrastructure
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | PageLoad
2016-08-23 12:32:55 +0000 [INFO]: | setPALSupportCookieForURL:
2016-08-23 12:32:55 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType:
2016-08-23 12:32:55 +0000 [INFO]: Use WebView to load the request.
2016-08-23 12:32:55 +0000 [VERBOSE]: WVCD: Requested Url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false
2016-08-23 12:32:55 +0000 [INFO]: WebScriptInputHandler: Load started
2016-08-23 12:32:55 +0000 [INFO]: WebScriptInputHandler: Page already preloaded, not triggering preload again
2016-08-23 12:32:55 +0000 [INFO]: | webViewDidStartLoad:
2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: webpage load started
2016-08-23 12:32:55 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType:
2016-08-23 12:32:55 +0000 [INFO]: Use WebView to load the request.
2016-08-23 12:32:55 +0000 [VERBOSE]: WVCD: Requested Url | about:blank
2016-08-23 12:32:55 +0000 [INFO]: WebScriptInputHandler: Load started
2016-08-23 12:32:55 +0000 [INFO]: WebScriptInputHandler: Page already preloaded, not triggering preload again
2016-08-23 12:32:55 +0000 [INFO]: | webViewDidStartLoad:
2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: webpage load started
2016-08-23 12:32:55 +0000 [VERBOSE]: WVCD: Loaded Url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false
2016-08-23 12:32:55 +0000 [INFO]: Load finished
2016-08-23 12:32:55 +0000 [INFO]: End event | PageLoad, 183.961987
2016-08-23 12:32:55 +0000 [INFO]: Mark event (end without start) | AppLaunch
2016-08-23 12:32:55 +0000 [INFO]: WebScriptBridge: App loading
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | DeviceReady
2016-08-23 12:32:55 +0000 [INFO]: WebScriptOutputHandler: Device ready
2016-08-23 12:32:55 +0000 [INFO]: Memory Diagnostics: Area (null); Event Device Ready; Memory used by app, total memory used by kernel, total free memory | 141.5, 1676.2, 64.0
2016-08-23 12:32:55 +0000 [INFO]: End event | DeviceReady, 0.872016
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | DispatchersAppActivate
2016-08-23 12:32:55 +0000 [INFO]: End event | DispatchersAppActivate, 0.028014
2016-08-23 12:32:55 +0000 [INFO]: | webViewDidFinishLoad:
2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: webpage load finished
2016-08-23 12:32:55 +0000 [VERBOSE]: WVCD: Loaded Url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false
2016-08-23 12:32:55 +0000 [INFO]: Load finished
2016-08-23 12:32:55 +0000 [INFO]: Mark event (end without start) | PageLoad
2016-08-23 12:32:55 +0000 [INFO]: Mark event (end without start) | AppLaunch
2016-08-23 12:32:55 +0000 [INFO]: | webViewDidFinishLoad:
2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: webpage load finished
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.020981
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:55 +0000 [INFO]: [PAL] | AppCache - AppCache exists : true
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.014007
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:55 +0000 [INFO]: [PAL] | AppCache - Manifest specified : true
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.016987
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:55 +0000 [INFO]: [PAL] | AppCache - Start Update
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.016034
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:55 +0000 [INFO]: [PAL] | Pending Log messages...
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.021994
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:55 +0000 [INFO]: [PAL] | Received appcache event...
2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Received Data in bearer token challenge"}}]
2016-08-23 12:32:55 +0000 [INFO]: Authentication: Received Data in bearer token challenge
2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: InitializeViewController: Token error"}}]
2016-08-23 12:32:55 +0000 [ERROR]: Authentication: InitializeViewController: Token error
2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Authentication Failed."}}]
2016-08-23 12:32:55 +0000 [INFO]: Authentication: Authentication Failed.
2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Authentication Interrupted"}}]
2016-08-23 12:32:55 +0000 [ERROR]: Authentication: Authentication Interrupted
2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Auth Completed."}}]
2016-08-23 12:32:55 +0000 [INFO]: Authentication: Auth Completed.
2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: Refresh
2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: validateAndLoadApp: Account is not configured; sending HEAD request to: | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:55 +0000 [INFO]: [PAL] | AppCache - Event : checking Status : Checking Details : {"isTrusted":true}
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.024021
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th0";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th0
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.301957
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 4
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019968
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th1";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th1
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.102043
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018954
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th2";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th2
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.048041
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.022054
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th3";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th3
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.118017
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018001
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th4";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th4
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.102997
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018954
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th5";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th5
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.105977
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th6";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th6
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.103056
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.016987
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th7";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th7
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.115991
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.021040
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th8";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th8
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.108004
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:55 +0000 [INFO]: [PAL] | Received appcache event...
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018001
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:55 +0000 [INFO]: [PAL] | AppCache - Event : noupdate Status : Idle Details : {"isTrusted":true}
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:55 +0000 [INFO]: [PAL] | Setting clientStatusFlag : AppCacheUpdateCompleted to true
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018001
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th9";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th9
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.117004
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th10";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th10
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.104964
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th11";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th11
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.104964
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019968
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th12";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th12
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.106037
2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.025988
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onStringsResult = "getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB13";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <ApplicationDispatcher: 0x17027c700>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onStringsResult, getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB13
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | getStringValueBundle
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.015974
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onStringsResult = "getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB14";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <ApplicationDispatcher: 0x17027c700>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onStringsResult, getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB14
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | getStringValueBundle
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.020981
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0>
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage
2016-08-23 12:32:55 +0000 [INFO]: [PAL] | Authentication: Starting
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs
2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014
2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {
onRequestSecurityTokenResponse = "requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u815";
}
2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <AccountDispatcher: 0x170463540>
2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onRequestSecurityTokenResponse, requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u815
2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | requestSecurityToken
2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Refreshing token"}}]
2016-08-23 12:32:55 +0000 [INFO]: Authentication: Refreshing token
2016-08-23 12:32:55 +0000 [VERBOSE]: Unregistering callback | requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u815
2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: validateAndLoadApp: HEAD request completed with | HTTP status code 200 and isValidServer 1
2016-08-23 12:32:55 +0000 [INFO]: | loadURL:
2016-08-23 12:32:55 +0000 [INFO]: Loading url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83
2016-08-23 12:32:55 +0000 [VERBOSE]: Setting ServerVersion | 8100000359
2016-08-23 12:32:55 +0000 [DEBUG]: in BackgroundTask
2016-08-23 12:32:55 +0000 [INFO]: | setupPALInfrastructure
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | PageLoad
2016-08-23 12:32:55 +0000 [INFO]: | setPALSupportCookieForURL:
2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Received Data in bearer token challenge"}}]
2016-08-23 12:32:55 +0000 [INFO]: Authentication: Received Data in bearer token challenge
2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: InitializeViewController: Token error"}}]
2016-08-23 12:32:55 +0000 [ERROR]: Authentication: InitializeViewController: Token error
2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Authentication Failed."}}]
2016-08-23 12:32:55 +0000 [INFO]: Authentication: Authentication Failed.
2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Authentication Interrupted"}}]
2016-08-23 12:32:55 +0000 [ERROR]: Authentication: Authentication Interrupted
2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Auth Completed."}}]
2016-08-23 12:32:55 +0000 [INFO]: Authentication: Auth Completed.
2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: Refresh
2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: validateAndLoadApp: Account is not configured; sending HEAD request to: | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83
2016-08-23 12:32:55 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType:
2016-08-23 12:32:55 +0000 [INFO]: Use WebView to load the request.
2016-08-23 12:32:55 +0000 [VERBOSE]: WVCD: Requested Url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false
2016-08-23 12:32:55 +0000 [INFO]: WebScriptInputHandler: Load started
2016-08-23 12:32:55 +0000 [INFO]: WebScriptInputHandler: Page already preloaded, not triggering preload again
2016-08-23 12:32:55 +0000 [INFO]: | webViewDidStartLoad:
2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: webpage load started
2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: validateAndLoadApp: HEAD request completed with | HTTP status code 200 and isValidServer 1
2016-08-23 12:32:55 +0000 [INFO]: | loadURL:
2016-08-23 12:32:55 +0000 [INFO]: Loading url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83
2016-08-23 12:32:55 +0000 [VERBOSE]: Setting ServerVersion | 8100000359
2016-08-23 12:32:55 +0000 [DEBUG]: in BackgroundTask
2016-08-23 12:32:55 +0000 [INFO]: | setupPALInfrastructure
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | PageLoad
2016-08-23 12:32:55 +0000 [INFO]: | setPALSupportCookieForURL:
2016-08-23 12:32:55 +0000 [VERBOSE]: WVCD: Loaded Url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false
2016-08-23 12:32:55 +0000 [INFO]: Load finished
2016-08-23 12:32:55 +0000 [INFO]: End event | PageLoad, 19.037008
2016-08-23 12:32:55 +0000 [INFO]: Mark event (end without start) | AppLaunch
2016-08-23 12:32:55 +0000 [INFO]: WebScriptBridge: App loading
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | DeviceReady
2016-08-23 12:32:55 +0000 [INFO]: WebScriptOutputHandler: Device ready
2016-08-23 12:32:55 +0000 [INFO]: Memory Diagnostics: Area (null); Event Device Ready; Memory used by app, total memory used by kernel, total free memory | 142.7, 1678.9, 52.4
2016-08-23 12:32:55 +0000 [INFO]: End event | DeviceReady, 0.523984
2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | DispatchersAppActivate
2016-08-23 12:32:55 +0000 [INFO]: End event | DispatchersAppActivate, 0.027955
2016-08-23 12:32:55 +0000 [INFO]: | webViewDidFinishLoad:
2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: webpage load finished
2016-08-23 12:32:55 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType:
2016-08-23 12:32:55 +0000 [INFO]: Use WebView to load the request.
2016-08-23 12:32:55 +0000 [VERBOSE]: WVCD: Requested Url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false
2016-08-23 12:32:55 +0000 [INFO]: WebScriptInputHandler: Load started
2016-08-23 12:32:55 +0000 [INFO]: WebScriptInputHandler: Page already preloaded, not triggering preload again
2016-08-23 12:32:55 +0000 [INFO]: | webViewDidStartLoad:
2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: webpage load started
2016-08-23 12:32:56 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType:
2016-08-23 12:32:56 +0000 [INFO]: Use WebView to load the request.
2016-08-23 12:32:56 +0000 [VERBOSE]: WVCD: Requested Url | about:blank
2016-08-23 12:32:56 +0000 [INFO]: WebScriptInputHandler: Load started
2016-08-23 12:32:56 +0000 [INFO]: WebScriptInputHandler: Page already preloaded, not triggering preload again
2016-08-23 12:32:56 +0000 [INFO]: | webViewDidStartLoad:
2016-08-23 12:32:56 +0000 [INFO]: CrmViewController: webpage load started
2016-08-23 12:32:56 +0000 [VERBOSE]: WVCD: Loaded Url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false
2016-08-23 12:32:56 +0000 [INFO]: Load finished
2016-08-23 12:32:56 +0000 [INFO]: Mark event (end without start) | PageLoad
2016-08-23 12:32:56 +0000 [INFO]: Mark event (end without start) | AppLaunch
2016-08-23 12:32:56 +0000 [INFO]: | webViewDidFinishLoad:
2016-08-23 12:32:56 +0000 [INFO]: CrmViewController: webpage load finished
2016-08-23 12:32:56 +0000 [VERBOSE]: WVCD: Loaded Url | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false
2016-08-23 12:32:56 +0000 [INFO]: Load finished
2016-08-23 12:32:56 +0000 [INFO]: Mark event (end without start) | PageLoad
2016-08-23 12:32:56 +0000 [INFO]: Mark event (end without start) | AppLaunch
2016-08-23 12:32:56 +0000 [INFO]: | webViewDidFinishLoad:
2016-08-23 12:32:56 +0000 [INFO]: CrmViewController: webpage load finished
2016-08-23 12:34:55 +0000 [ERROR]: App load cancelled for URL | crm2016.xxxxxx.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false
2016-08-23 12:34:58 +0000 [INFO]: CrmViewController.showLoginOrErrorView: pushViewController=UserLoginScreenViewController animated=NO
2016-08-23 12:34:58 +0000 [INFO]: Navigating to about:blank in viewWillDisappear (isConfigured;authFailed;isSignedOut;TriggerAppReload) = | 0,1,0,1
2016-08-23 12:34:58 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType:
2016-08-23 12:34:58 +0000 [INFO]: Use WebView to load the request.
2016-08-23 12:34:58 +0000 [VERBOSE]: WVCD: Requested Url | about:blank
2016-08-23 12:34:58 +0000 [INFO]: WebScriptInputHandler: Load started
2016-08-23 12:34:58 +0000 [INFO]: WebScriptInputHandler: Page already preloaded, not triggering preload again
2016-08-23 12:34:58 +0000 [INFO]: | webViewDidStartLoad:
2016-08-23 12:34:58 +0000 [INFO]: CrmViewController: webpage load started
2016-08-23 12:34:58 +0000 [VERBOSE]: WVCD: Loaded Url | about:blank
2016-08-23 12:34:58 +0000 [INFO]: Load finished
2016-08-23 12:34:58 +0000 [INFO]: Mark event (end without start) | PageLoad
2016-08-23 12:34:58 +0000 [INFO]: Mark event (end without start) | AppLaunch
2016-08-23 12:34:58 +0000 [INFO]: | webViewDidFinishLoad:
2016-08-23 12:34:58 +0000 [INFO]: CrmViewController: webpage load finished
2016-08-23 12:35:00 +0000 [INFO]: | appPause
2016-08-23 12:35:00 +0000 [INFO]: Application Pause
Somebody have an idea ?
Thanks,
2016-08-23 12:35:00 +0000 [INFO]: Application pause
2016-08-23 12:35:00 +0000 [INFO]: WebScriptBridge: App pause
2016-08-23 12:35:00 +0000 [VERBOSE]: Start event | DispatchersAppPause
2016-08-23 12:35:00 +0000 [INFO]: End event | DispatchersAppPause, 0.028968
2016-08-23 12:35:00 +0000 [VERBOSE]: Start event | DevicePause
2016-08-23 12:35:00 +0000 [INFO]: End event | DevicePause, 0.566006
2016-08-23 12:35:01 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Session_Complete","EventValues":{"ActiveSessionTime":130.797613}}]
2016-08-23 12:32:51 +0000 [INFO]: App Version Number | 3.1.0.62 2016-08-23 12:32:51 +0000 [INFO]: App Short Version | 8.3 2016-08-23 12:32:51 +0000 [INFO]: | appLaunchWithArgs: 2016-08-23 12:32:51 +0000 [INFO]: Application launched 2016-08-23 12:32:51 +0000 [INFO]: | appResume 2016-08-23 12:32:51 +0000 [INFO]: CrmViewController: Application resume 2016-08-23 12:32:51 +0000 [INFO]: | viewDidLoad 2016-08-23 12:32:51 +0000 [VERBOSE]: Setting IsFirstLoad | 1 2016-08-23 12:32:51 +0000 [VERBOSE]: Start event | InitBridge 2016-08-23 12:32:51 +0000 [VERBOSE]: Setting Username | (null) 2016-08-23 12:32:51 +0000 [INFO]: End event | InitBridge, 2.245009 2016-08-23 12:32:51 +0000 [INFO]: Mark event (end without start) | ShellLaunch 2016-08-23 12:32:51 +0000 [VERBOSE]: Setting Username | (null) 2016-08-23 12:32:51 +0000 [INFO]: CrmViewController.showLoginOrErrorView: pushViewController=UserLoginScreenViewController animated=NO 2016-08-23 12:32:52 +0000 [INFO]: User given URL | https://crm2016.mediaperf.com 2016-08-23 12:32:52 +0000 [INFO]: Constructed server URL | https://crm2016.mediaperf.com 2016-08-23 12:32:52 +0000 [INFO]: CrmViewController: viewWillAppear 2016-08-23 12:32:52 +0000 [INFO]: CrmViewController: Navigating browser to the app URL 2016-08-23 12:32:52 +0000 [INFO]: CrmViewController: validateAndLoadApp: Account is not configured; sending HEAD request to: | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83 2016-08-23 12:32:53 +0000 [INFO]: CrmViewController: validateAndLoadApp: HEAD request completed with | HTTP status code 200 and isValidServer 1 2016-08-23 12:32:53 +0000 [INFO]: | loadURL: 2016-08-23 12:32:53 +0000 [INFO]: Loading url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83 2016-08-23 12:32:53 +0000 [VERBOSE]: Setting IsFirstLoad | 0 2016-08-23 12:32:53 +0000 [VERBOSE]: Setting ServerVersion | 8100000359 2016-08-23 12:32:53 +0000 [DEBUG]: in BackgroundTask 2016-08-23 12:32:53 +0000 [INFO]: | setupPALInfrastructure 2016-08-23 12:32:53 +0000 [VERBOSE]: Start event | PageLoad 2016-08-23 12:32:53 +0000 [INFO]: | setPALSupportCookieForURL: 2016-08-23 12:32:53 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType: 2016-08-23 12:32:53 +0000 [INFO]: Use WebView to load the request. 2016-08-23 12:32:53 +0000 [VERBOSE]: WVCD: Requested Url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=true 2016-08-23 12:32:53 +0000 [INFO]: WebScriptInputHandler: Load started 2016-08-23 12:32:53 +0000 [INFO]: WebScriptBridge: App preloading 2016-08-23 12:32:53 +0000 [VERBOSE]: Start event | DispatchersAppLoad 2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {} 2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {} 2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {} 2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {} 2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {} 2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {} 2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {} 2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {} 2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {} 2016-08-23 12:32:53 +0000 [VERBOSE]: appLoad | {} 2016-08-23 12:32:53 +0000 [INFO]: End event | DispatchersAppLoad, 16.600013 2016-08-23 12:32:53 +0000 [INFO]: | webViewDidStartLoad: 2016-08-23 12:32:53 +0000 [INFO]: CrmViewController: webpage load started 2016-08-23 12:32:53 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType: 2016-08-23 12:32:53 +0000 [INFO]: Use WebView to load the request. 2016-08-23 12:32:53 +0000 [VERBOSE]: WVCD: Requested Url | about:blank 2016-08-23 12:32:53 +0000 [INFO]: WebScriptInputHandler: Load started 2016-08-23 12:32:53 +0000 [INFO]: WebScriptInputHandler: Page already preloaded, not triggering preload again 2016-08-23 12:32:53 +0000 [INFO]: | webViewDidStartLoad: 2016-08-23 12:32:53 +0000 [INFO]: CrmViewController: webpage load started 2016-08-23 12:32:53 +0000 [VERBOSE]: WVCD: Loaded Url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=true 2016-08-23 12:32:53 +0000 [INFO]: Load finished 2016-08-23 12:32:53 +0000 [INFO]: End event | PageLoad, 758.005023 2016-08-23 12:32:53 +0000 [INFO]: Mark event (end without start) | AppLaunch 2016-08-23 12:32:53 +0000 [INFO]: WebScriptBridge: App loading 2016-08-23 12:32:53 +0000 [VERBOSE]: Start event | DeviceReady 2016-08-23 12:32:53 +0000 [INFO]: WebScriptOutputHandler: Device ready 2016-08-23 12:32:53 +0000 [INFO]: Memory Diagnostics: Area (null); Event Device Ready; Memory used by app, total memory used by kernel, total free memory | 99.4, 1725.3, 32.2 2016-08-23 12:32:53 +0000 [INFO]: End event | DeviceReady, 2.382994 2016-08-23 12:32:53 +0000 [VERBOSE]: Start event | DispatchersAppActivate 2016-08-23 12:32:53 +0000 [INFO]: End event | DispatchersAppActivate, 0.082970 2016-08-23 12:32:53 +0000 [INFO]: | webViewDidFinishLoad: 2016-08-23 12:32:53 +0000 [INFO]: CrmViewController: webpage load finished 2016-08-23 12:32:53 +0000 [VERBOSE]: WVCD: Loaded Url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=true 2016-08-23 12:32:53 +0000 [INFO]: Load finished 2016-08-23 12:32:53 +0000 [INFO]: Mark event (end without start) | PageLoad 2016-08-23 12:32:53 +0000 [INFO]: Mark event (end without start) | AppLaunch 2016-08-23 12:32:53 +0000 [INFO]: | webViewDidFinishLoad: 2016-08-23 12:32:53 +0000 [INFO]: CrmViewController: webpage load finished 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.050008 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | logMessage, { level = NSNumber; message = NSString; version = NSString;}, "()", "( version, message, level)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | logPerformanceData, { data = NSString; namespace = NSString; version = NSString;}, "()", "( version, namespace, data)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:54 +0000 [INFO]: [PAL] | AppCache - AppCache exists : true 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.018001 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:54 +0000 [INFO]: [PAL] | AppCache - Manifest specified : true 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.015020 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:54 +0000 [INFO]: [PAL] | AppCache - Start Update 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.014007 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:54 +0000 [INFO]: [PAL] | Pending Log messages... 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.017047 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:54 +0000 [INFO]: [PAL] | Received appcache event... 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.018001 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:54 +0000 [INFO]: [PAL] | AppCache - Event : checking Status : Checking Details : {"isTrusted":true} 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.020981 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th0";} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th0 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | executeSqlBatch, { callContext = DispatcherCallContext; parameterBatch = NSArray; statementBatch = NSArray; version = NSString;}, "( onBatchComplete)", "( version, callContext, statementBatch, parameterBatch, onBatchComplete)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.019968 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:54 +0000 [INFO]: [PAL] | Received appcache event... 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.018001 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:54 +0000 [INFO]: [PAL] | AppCache - Event : noupdate Status : Idle Details : {"isTrusted":true} 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 21.890998 2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 4 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.015974 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:54 +0000 [INFO]: [PAL] | Setting clientStatusFlag : AppCacheUpdateCompleted to true 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.018001 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th1";} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th1 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.141978 2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.018954 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th2";} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th2 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.055969 2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.020981 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th3";} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th3 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.186026 2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.020027 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th4";} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th4 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.169992 2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.022054 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th5";} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th5 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.164986 2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.019014 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th6";} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th6 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.150979 2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.016987 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th7";} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th7 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.135005 2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.020981 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th8";} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th8 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.171959 2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.019968 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th9";} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th9 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.116944 2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.018954 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th10";} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th10 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.101030 2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.020981 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th11";} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th11 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.102997 2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.018954 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th12";} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th12 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | SQLBatch, 0.104010 2016-08-23 12:32:54 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.027001 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | { onStringsResult = "getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB13";} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <ApplicationDispatcher: 0x17027c700> 2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onStringsResult, getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB13 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | getBoolValue, { valueName = NSString; version = NSString;}, "( onBoolResult)", "( version, valueName, onBoolResult)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | setBoolValue, { value = NSNumber; valueName = NSString; version = NSString;}, "()", "( version, valueName, value)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | getStringValueBundle, { valueNames = NSArray; version = NSString;}, "( onStringsResult)", "( version, valueNames, onStringsResult)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | getMemoryUsage, { version = NSString;}, "( onFloatResult)", "( version, onFloatResult)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | captureMemoryUsage, { area = NSString; eventName = NSString; version = NSString;}, "( onFloatResult)", "( version, area, eventName, onFloatResult)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | changeMemoryDiagnosticsEnableState, { memoryDiagnosticsEnabled = NSNumber; version = NSString;}, "()", "( version, memoryDiagnosticsEnabled)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | clientReady, { clientState = NSDictionary; version = NSString;}, "()", "( version, clientState)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | clientInitialized, { version = NSString;}, "()", "( version)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | clientBootError, { deviceErrorAction = NSNumber; errorDetails = NSDictionary; version = NSString;}, "()", "( version, deviceErrorAction, errorDetails)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | setClientSettingsPackage, { value = NSDictionary; valueName = NSString; version = NSString;}, "()", "( version, valueName, value)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | getStringValueBundle 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.018001 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | { onStringsResult = "getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB14";} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <ApplicationDispatcher: 0x17027c700> 2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onStringsResult, getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB14 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | getStringValueBundle 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.021994 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:54 +0000 [INFO]: [PAL] | Authentication: Starting 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.016987 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | { onRequestSecurityTokenResponse = "requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u815";} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <AccountDispatcher: 0x170463540> 2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onRequestSecurityTokenResponse, requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u815 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | requestSecurityToken, { refreshToken = NSNumber; version = NSString;}, "( onRequestSecurityTokenResponse)", "( version, refreshToken, onRequestSecurityTokenResponse)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | signOut, { version = NSString;}, "()", "( version)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | reconfigure, { version = NSString;}, "()", "( version)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | cancelSignIn, { version = NSString;}, "()", "( version)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | authValidationError, { deviceErrorAction = NSNumber; errorDetails = NSDictionary; version = NSString;}, "()", "( version, deviceErrorAction, errorDetails)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Registering method | setSessionID, { sessionID = NSString; telemetryEndPointUrl = NSString; version = NSString;}, "()", "( version, sessionID, telemetryEndPointUrl)" 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | requestSecurityToken 2016-08-23 12:32:54 +0000 [VERBOSE]: KeyChainQuery | { acct = AuthToken; class = genp; "m_Limit" = "m_LimitOne"; pdmn = ck; "r_Data" = 1; svce = "Microsoft Office";} 2016-08-23 12:32:54 +0000 [WARNING]: KeyChainItem operation failed. | Operaton 'CopyToken' failed with 'ItemNotFound'. Error code:-25300 2016-08-23 12:32:54 +0000 [ERROR]: Telemetry Reporter: Telemetry EndPoint not set. 2016-08-23 12:32:54 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Refreshing token"}}] 2016-08-23 12:32:54 +0000 [INFO]: Authentication: Refreshing token 2016-08-23 12:32:54 +0000 [VERBOSE]: Unregistering callback | requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u815 2016-08-23 12:32:54 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Received Data in bearer token challenge"}}] 2016-08-23 12:32:54 +0000 [INFO]: Authentication: Received Data in bearer token challenge 2016-08-23 12:32:54 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: InitializeViewController: Token error"}}] 2016-08-23 12:32:54 +0000 [ERROR]: Authentication: InitializeViewController: Token error 2016-08-23 12:32:54 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Authentication Failed."}}] 2016-08-23 12:32:54 +0000 [INFO]: Authentication: Authentication Failed. 2016-08-23 12:32:54 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Authentication Interrupted"}}] 2016-08-23 12:32:54 +0000 [ERROR]: Authentication: Authentication Interrupted 2016-08-23 12:32:54 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Auth Completed."}}] 2016-08-23 12:32:54 +0000 [INFO]: Authentication: Auth Completed. 2016-08-23 12:32:54 +0000 [INFO]: CrmViewController: Refresh 2016-08-23 12:32:54 +0000 [INFO]: CrmViewController: validateAndLoadApp: Account is not configured; sending HEAD request to: | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.020027 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | { onRequestSecurityTokenResponse = "requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u816";} 2016-08-23 12:32:54 +0000 [VERBOSE]: Dispatcher found | <AccountDispatcher: 0x170463540> 2016-08-23 12:32:54 +0000 [VERBOSE]: Creating callback object | onRequestSecurityTokenResponse, requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u816 2016-08-23 12:32:54 +0000 [VERBOSE]: Calling registered method | requestSecurityToken 2016-08-23 12:32:54 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Refreshing token"}}] 2016-08-23 12:32:54 +0000 [INFO]: Authentication: Refreshing token 2016-08-23 12:32:54 +0000 [VERBOSE]: Unregistering callback | requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u816 2016-08-23 12:32:54 +0000 [INFO]: CrmViewController: validateAndLoadApp: HEAD request completed with | HTTP status code 200 and isValidServer 1 2016-08-23 12:32:54 +0000 [INFO]: | loadURL: 2016-08-23 12:32:54 +0000 [INFO]: Loading url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83 2016-08-23 12:32:54 +0000 [VERBOSE]: Setting ServerVersion | 8100000359 2016-08-23 12:32:54 +0000 [DEBUG]: in BackgroundTask 2016-08-23 12:32:54 +0000 [INFO]: | setupPALInfrastructure 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | PageLoad 2016-08-23 12:32:54 +0000 [INFO]: | setPALSupportCookieForURL: 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:54 +0000 [VERBOSE]: End event | ParseArgs, 0.035048 2016-08-23 12:32:54 +0000 [VERBOSE]: Callbacks | { onRequestSecurityTokenResponse = "requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u817";} 2016-08-23 12:32:54 +0000 [WARNING]: Dispatch: Bridge not ready 2016-08-23 12:32:54 +0000 [WARNING]: ScriptInput: Call method failed | 1 2016-08-23 12:32:54 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType: 2016-08-23 12:32:54 +0000 [INFO]: Use WebView to load the request. 2016-08-23 12:32:54 +0000 [VERBOSE]: WVCD: Requested Url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false 2016-08-23 12:32:54 +0000 [INFO]: WebScriptInputHandler: Load started 2016-08-23 12:32:54 +0000 [INFO]: WebScriptInputHandler: Page already preloaded, not triggering preload again 2016-08-23 12:32:54 +0000 [INFO]: | webViewDidStartLoad: 2016-08-23 12:32:54 +0000 [INFO]: CrmViewController: webpage load started 2016-08-23 12:32:54 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType: 2016-08-23 12:32:54 +0000 [INFO]: Use WebView to load the request. 2016-08-23 12:32:54 +0000 [VERBOSE]: WVCD: Requested Url | about:blank 2016-08-23 12:32:54 +0000 [INFO]: WebScriptInputHandler: Load started 2016-08-23 12:32:54 +0000 [INFO]: WebScriptInputHandler: Page already preloaded, not triggering preload again 2016-08-23 12:32:54 +0000 [INFO]: | webViewDidStartLoad: 2016-08-23 12:32:54 +0000 [INFO]: CrmViewController: webpage load started 2016-08-23 12:32:54 +0000 [VERBOSE]: WVCD: Loaded Url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false 2016-08-23 12:32:54 +0000 [INFO]: Load finished 2016-08-23 12:32:54 +0000 [INFO]: End event | PageLoad, 197.615027 2016-08-23 12:32:54 +0000 [INFO]: Mark event (end without start) | AppLaunch 2016-08-23 12:32:54 +0000 [INFO]: WebScriptBridge: App loading 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | DeviceReady 2016-08-23 12:32:54 +0000 [INFO]: WebScriptOutputHandler: Device ready 2016-08-23 12:32:54 +0000 [INFO]: Memory Diagnostics: Area (null); Event Device Ready; Memory used by app, total memory used by kernel, total free memory | 136.3, 1705.6, 36.4 2016-08-23 12:32:54 +0000 [INFO]: End event | DeviceReady, 0.882983 2016-08-23 12:32:54 +0000 [VERBOSE]: Start event | DispatchersAppActivate 2016-08-23 12:32:54 +0000 [INFO]: End event | DispatchersAppActivate, 0.032008 2016-08-23 12:32:54 +0000 [INFO]: | webViewDidFinishLoad: 2016-08-23 12:32:54 +0000 [INFO]: CrmViewController: webpage load finished 2016-08-23 12:32:54 +0000 [VERBOSE]: WVCD: Loaded Url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false 2016-08-23 12:32:54 +0000 [INFO]: Load finished 2016-08-23 12:32:54 +0000 [INFO]: Mark event (end without start) | PageLoad 2016-08-23 12:32:54 +0000 [INFO]: Mark event (end without start) | AppLaunch 2016-08-23 12:32:54 +0000 [INFO]: | webViewDidFinishLoad: 2016-08-23 12:32:54 +0000 [INFO]: CrmViewController: webpage load finished 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.021994 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:55 +0000 [INFO]: [PAL] | AppCache - AppCache exists : true 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.015974 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:55 +0000 [INFO]: [PAL] | AppCache - Manifest specified : true 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.015020 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:55 +0000 [INFO]: [PAL] | AppCache - Start Update 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:55 +0000 [INFO]: [PAL] | Pending Log messages... 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.021994 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th0";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th0 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.651002 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 4 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018001 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th1";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th1 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.106990 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018001 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th2";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th2 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.047982 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.020027 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th3";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th3 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.109017 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th4";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th4 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.105023 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.020027 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th5";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th5 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.104964 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th6";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th6 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.108004 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018954 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th7";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th7 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.096977 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.020027 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th8";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th8 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.194967 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th9";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th9 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.115991 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.020027 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th10";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th10 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.099957 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018954 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th11";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th11 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.104010 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018954 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th12";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th12 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.106990 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.025034 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onStringsResult = "getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB13";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <ApplicationDispatcher: 0x17027c700> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onStringsResult, getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB13 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | getStringValueBundle 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.014961 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onStringsResult = "getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB14";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <ApplicationDispatcher: 0x17027c700> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onStringsResult, getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB14 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | getStringValueBundle 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.021994 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:55 +0000 [INFO]: [PAL] | Authentication: Starting 2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Received Data in bearer token challenge"}}] 2016-08-23 12:32:55 +0000 [INFO]: Authentication: Received Data in bearer token challenge 2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: InitializeViewController: Token error"}}] 2016-08-23 12:32:55 +0000 [ERROR]: Authentication: InitializeViewController: Token error 2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Authentication Failed."}}] 2016-08-23 12:32:55 +0000 [INFO]: Authentication: Authentication Failed. 2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Authentication Interrupted"}}] 2016-08-23 12:32:55 +0000 [ERROR]: Authentication: Authentication Interrupted 2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Auth Completed."}}] 2016-08-23 12:32:55 +0000 [INFO]: Authentication: Auth Completed. 2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: Refresh 2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: validateAndLoadApp: Account is not configured; sending HEAD request to: | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018954 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onRequestSecurityTokenResponse = "requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u815";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <AccountDispatcher: 0x170463540> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onRequestSecurityTokenResponse, requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u815 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | requestSecurityToken 2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Refreshing token"}}] 2016-08-23 12:32:55 +0000 [INFO]: Authentication: Refreshing token 2016-08-23 12:32:55 +0000 [VERBOSE]: Unregistering callback | requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u815 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018001 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:55 +0000 [INFO]: [PAL] | Received appcache event... 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.016987 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:55 +0000 [INFO]: [PAL] | AppCache - Event : noupdate Status : Idle Details : {"isTrusted":true} 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.017047 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:55 +0000 [INFO]: [PAL] | Setting clientStatusFlag : AppCacheUpdateCompleted to true 2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: validateAndLoadApp: HEAD request completed with | HTTP status code 200 and isValidServer 1 2016-08-23 12:32:55 +0000 [INFO]: | loadURL: 2016-08-23 12:32:55 +0000 [INFO]: Loading url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83 2016-08-23 12:32:55 +0000 [VERBOSE]: Setting ServerVersion | 8100000359 2016-08-23 12:32:55 +0000 [DEBUG]: in BackgroundTask 2016-08-23 12:32:55 +0000 [INFO]: | setupPALInfrastructure 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | PageLoad 2016-08-23 12:32:55 +0000 [INFO]: | setPALSupportCookieForURL: 2016-08-23 12:32:55 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType: 2016-08-23 12:32:55 +0000 [INFO]: Use WebView to load the request. 2016-08-23 12:32:55 +0000 [VERBOSE]: WVCD: Requested Url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false 2016-08-23 12:32:55 +0000 [INFO]: WebScriptInputHandler: Load started 2016-08-23 12:32:55 +0000 [INFO]: WebScriptInputHandler: Page already preloaded, not triggering preload again 2016-08-23 12:32:55 +0000 [INFO]: | webViewDidStartLoad: 2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: webpage load started 2016-08-23 12:32:55 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType: 2016-08-23 12:32:55 +0000 [INFO]: Use WebView to load the request. 2016-08-23 12:32:55 +0000 [VERBOSE]: WVCD: Requested Url | about:blank 2016-08-23 12:32:55 +0000 [INFO]: WebScriptInputHandler: Load started 2016-08-23 12:32:55 +0000 [INFO]: WebScriptInputHandler: Page already preloaded, not triggering preload again 2016-08-23 12:32:55 +0000 [INFO]: | webViewDidStartLoad: 2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: webpage load started 2016-08-23 12:32:55 +0000 [VERBOSE]: WVCD: Loaded Url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false 2016-08-23 12:32:55 +0000 [INFO]: Load finished 2016-08-23 12:32:55 +0000 [INFO]: End event | PageLoad, 183.961987 2016-08-23 12:32:55 +0000 [INFO]: Mark event (end without start) | AppLaunch 2016-08-23 12:32:55 +0000 [INFO]: WebScriptBridge: App loading 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | DeviceReady 2016-08-23 12:32:55 +0000 [INFO]: WebScriptOutputHandler: Device ready 2016-08-23 12:32:55 +0000 [INFO]: Memory Diagnostics: Area (null); Event Device Ready; Memory used by app, total memory used by kernel, total free memory | 141.5, 1676.2, 64.0 2016-08-23 12:32:55 +0000 [INFO]: End event | DeviceReady, 0.872016 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | DispatchersAppActivate 2016-08-23 12:32:55 +0000 [INFO]: End event | DispatchersAppActivate, 0.028014 2016-08-23 12:32:55 +0000 [INFO]: | webViewDidFinishLoad: 2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: webpage load finished 2016-08-23 12:32:55 +0000 [VERBOSE]: WVCD: Loaded Url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false 2016-08-23 12:32:55 +0000 [INFO]: Load finished 2016-08-23 12:32:55 +0000 [INFO]: Mark event (end without start) | PageLoad 2016-08-23 12:32:55 +0000 [INFO]: Mark event (end without start) | AppLaunch 2016-08-23 12:32:55 +0000 [INFO]: | webViewDidFinishLoad: 2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: webpage load finished 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.020981 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:55 +0000 [INFO]: [PAL] | AppCache - AppCache exists : true 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.014007 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:55 +0000 [INFO]: [PAL] | AppCache - Manifest specified : true 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.016987 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:55 +0000 [INFO]: [PAL] | AppCache - Start Update 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.016034 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:55 +0000 [INFO]: [PAL] | Pending Log messages... 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.021994 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:55 +0000 [INFO]: [PAL] | Received appcache event... 2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Received Data in bearer token challenge"}}] 2016-08-23 12:32:55 +0000 [INFO]: Authentication: Received Data in bearer token challenge 2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: InitializeViewController: Token error"}}] 2016-08-23 12:32:55 +0000 [ERROR]: Authentication: InitializeViewController: Token error 2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Authentication Failed."}}] 2016-08-23 12:32:55 +0000 [INFO]: Authentication: Authentication Failed. 2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Authentication Interrupted"}}] 2016-08-23 12:32:55 +0000 [ERROR]: Authentication: Authentication Interrupted 2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Auth Completed."}}] 2016-08-23 12:32:55 +0000 [INFO]: Authentication: Auth Completed. 2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: Refresh 2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: validateAndLoadApp: Account is not configured; sending HEAD request to: | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:55 +0000 [INFO]: [PAL] | AppCache - Event : checking Status : Checking Details : {"isTrusted":true} 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.024021 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th0";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th0 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.301957 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 4 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019968 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th1";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th1 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.102043 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018954 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th2";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th2 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.048041 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.022054 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th3";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th3 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.118017 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018001 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th4";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th4 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.102997 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018954 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th5";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th5 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.105977 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th6";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th6 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.103056 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.016987 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th7";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th7 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.115991 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.021040 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th8";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th8 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.108004 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:55 +0000 [INFO]: [PAL] | Received appcache event... 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018001 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:55 +0000 [INFO]: [PAL] | AppCache - Event : noupdate Status : Idle Details : {"isTrusted":true} 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:55 +0000 [INFO]: [PAL] | Setting clientStatusFlag : AppCacheUpdateCompleted to true 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.018001 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th9";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th9 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.117004 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th10";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th10 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.104964 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th11";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th11 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.104964 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019968 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onBatchComplete = "executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th12";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <SqlDispatcher: 0x17008efb0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onBatchComplete, executeSqlBatchMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$5x.$th12 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | executeSqlBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | SQLBatch 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | SQLBatch, 0.106037 2016-08-23 12:32:55 +0000 [VERBOSE]: SQL Batch Finished - Number of ops | 1 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.025988 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onStringsResult = "getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB13";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <ApplicationDispatcher: 0x17027c700> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onStringsResult, getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB13 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | getStringValueBundle 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.015974 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onStringsResult = "getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB14";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <ApplicationDispatcher: 0x17027c700> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onStringsResult, getStringValueBundleMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$PY.$uB14 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | getStringValueBundle 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.020981 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | {} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <LoggingDispatcher: 0x1704601c0> 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | logMessage 2016-08-23 12:32:55 +0000 [INFO]: [PAL] | Authentication: Starting 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | ParseArgs 2016-08-23 12:32:55 +0000 [VERBOSE]: End event | ParseArgs, 0.019014 2016-08-23 12:32:55 +0000 [VERBOSE]: Callbacks | { onRequestSecurityTokenResponse = "requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u815";} 2016-08-23 12:32:55 +0000 [VERBOSE]: Dispatcher found | <AccountDispatcher: 0x170463540> 2016-08-23 12:32:55 +0000 [VERBOSE]: Creating callback object | onRequestSecurityTokenResponse, requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u815 2016-08-23 12:32:55 +0000 [VERBOSE]: Calling registered method | requestSecurityToken 2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Refreshing token"}}] 2016-08-23 12:32:55 +0000 [INFO]: Authentication: Refreshing token 2016-08-23 12:32:55 +0000 [VERBOSE]: Unregistering callback | requestSecurityTokenMicrosoft.Crm.Client.Core.Framework.PAL.Dispatchers.$Pv.$u815 2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: validateAndLoadApp: HEAD request completed with | HTTP status code 200 and isValidServer 1 2016-08-23 12:32:55 +0000 [INFO]: | loadURL: 2016-08-23 12:32:55 +0000 [INFO]: Loading url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83 2016-08-23 12:32:55 +0000 [VERBOSE]: Setting ServerVersion | 8100000359 2016-08-23 12:32:55 +0000 [DEBUG]: in BackgroundTask 2016-08-23 12:32:55 +0000 [INFO]: | setupPALInfrastructure 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | PageLoad 2016-08-23 12:32:55 +0000 [INFO]: | setPALSupportCookieForURL: 2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Received Data in bearer token challenge"}}] 2016-08-23 12:32:55 +0000 [INFO]: Authentication: Received Data in bearer token challenge 2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: InitializeViewController: Token error"}}] 2016-08-23 12:32:55 +0000 [ERROR]: Authentication: InitializeViewController: Token error 2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Authentication Failed."}}] 2016-08-23 12:32:55 +0000 [INFO]: Authentication: Authentication Failed. 2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Authentication Interrupted"}}] 2016-08-23 12:32:55 +0000 [ERROR]: Authentication: Authentication Interrupted 2016-08-23 12:32:55 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Authentication_Trace","EventValues":{"Message":"Authentication: Auth Completed."}}] 2016-08-23 12:32:55 +0000 [INFO]: Authentication: Auth Completed. 2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: Refresh 2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: validateAndLoadApp: Account is not configured; sending HEAD request to: | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83 2016-08-23 12:32:55 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType: 2016-08-23 12:32:55 +0000 [INFO]: Use WebView to load the request. 2016-08-23 12:32:55 +0000 [VERBOSE]: WVCD: Requested Url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false 2016-08-23 12:32:55 +0000 [INFO]: WebScriptInputHandler: Load started 2016-08-23 12:32:55 +0000 [INFO]: WebScriptInputHandler: Page already preloaded, not triggering preload again 2016-08-23 12:32:55 +0000 [INFO]: | webViewDidStartLoad: 2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: webpage load started 2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: validateAndLoadApp: HEAD request completed with | HTTP status code 200 and isValidServer 1 2016-08-23 12:32:55 +0000 [INFO]: | loadURL: 2016-08-23 12:32:55 +0000 [INFO]: Loading url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83 2016-08-23 12:32:55 +0000 [VERBOSE]: Setting ServerVersion | 8100000359 2016-08-23 12:32:55 +0000 [DEBUG]: in BackgroundTask 2016-08-23 12:32:55 +0000 [INFO]: | setupPALInfrastructure 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | PageLoad 2016-08-23 12:32:55 +0000 [INFO]: | setPALSupportCookieForURL: 2016-08-23 12:32:55 +0000 [VERBOSE]: WVCD: Loaded Url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false 2016-08-23 12:32:55 +0000 [INFO]: Load finished 2016-08-23 12:32:55 +0000 [INFO]: End event | PageLoad, 19.037008 2016-08-23 12:32:55 +0000 [INFO]: Mark event (end without start) | AppLaunch 2016-08-23 12:32:55 +0000 [INFO]: WebScriptBridge: App loading 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | DeviceReady 2016-08-23 12:32:55 +0000 [INFO]: WebScriptOutputHandler: Device ready 2016-08-23 12:32:55 +0000 [INFO]: Memory Diagnostics: Area (null); Event Device Ready; Memory used by app, total memory used by kernel, total free memory | 142.7, 1678.9, 52.4 2016-08-23 12:32:55 +0000 [INFO]: End event | DeviceReady, 0.523984 2016-08-23 12:32:55 +0000 [VERBOSE]: Start event | DispatchersAppActivate 2016-08-23 12:32:55 +0000 [INFO]: End event | DispatchersAppActivate, 0.027955 2016-08-23 12:32:55 +0000 [INFO]: | webViewDidFinishLoad: 2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: webpage load finished 2016-08-23 12:32:55 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType: 2016-08-23 12:32:55 +0000 [INFO]: Use WebView to load the request. 2016-08-23 12:32:55 +0000 [VERBOSE]: WVCD: Requested Url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false 2016-08-23 12:32:55 +0000 [INFO]: WebScriptInputHandler: Load started 2016-08-23 12:32:55 +0000 [INFO]: WebScriptInputHandler: Page already preloaded, not triggering preload again 2016-08-23 12:32:55 +0000 [INFO]: | webViewDidStartLoad: 2016-08-23 12:32:55 +0000 [INFO]: CrmViewController: webpage load started 2016-08-23 12:32:56 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType: 2016-08-23 12:32:56 +0000 [INFO]: Use WebView to load the request. 2016-08-23 12:32:56 +0000 [VERBOSE]: WVCD: Requested Url | about:blank 2016-08-23 12:32:56 +0000 [INFO]: WebScriptInputHandler: Load started 2016-08-23 12:32:56 +0000 [INFO]: WebScriptInputHandler: Page already preloaded, not triggering preload again 2016-08-23 12:32:56 +0000 [INFO]: | webViewDidStartLoad: 2016-08-23 12:32:56 +0000 [INFO]: CrmViewController: webpage load started 2016-08-23 12:32:56 +0000 [VERBOSE]: WVCD: Loaded Url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false 2016-08-23 12:32:56 +0000 [INFO]: Load finished 2016-08-23 12:32:56 +0000 [INFO]: Mark event (end without start) | PageLoad 2016-08-23 12:32:56 +0000 [INFO]: Mark event (end without start) | AppLaunch 2016-08-23 12:32:56 +0000 [INFO]: | webViewDidFinishLoad: 2016-08-23 12:32:56 +0000 [INFO]: CrmViewController: webpage load finished 2016-08-23 12:32:56 +0000 [VERBOSE]: WVCD: Loaded Url | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false 2016-08-23 12:32:56 +0000 [INFO]: Load finished 2016-08-23 12:32:56 +0000 [INFO]: Mark event (end without start) | PageLoad 2016-08-23 12:32:56 +0000 [INFO]: Mark event (end without start) | AppLaunch 2016-08-23 12:32:56 +0000 [INFO]: | webViewDidFinishLoad: 2016-08-23 12:32:56 +0000 [INFO]: CrmViewController: webpage load finished 2016-08-23 12:34:55 +0000 [ERROR]: App load cancelled for URL | crm2016.mediaperf.com/.../main.htm;liveid=1&installid=F51D1183-9240-4A40-AA53-E0034647BB83&isfirstload=false 2016-08-23 12:34:58 +0000 [INFO]: CrmViewController.showLoginOrErrorView: pushViewController=UserLoginScreenViewController animated=NO 2016-08-23 12:34:58 +0000 [INFO]: Navigating to about:blank in viewWillDisappear (isConfigured;authFailed;isSignedOut;TriggerAppReload) = | 0,1,0,1 2016-08-23 12:34:58 +0000 [INFO]: | webView:shouldStartLoadWithRequest:navigationType: 2016-08-23 12:34:58 +0000 [INFO]: Use WebView to load the request. 2016-08-23 12:34:58 +0000 [VERBOSE]: WVCD: Requested Url | about:blank 2016-08-23 12:34:58 +0000 [INFO]: WebScriptInputHandler: Load started 2016-08-23 12:34:58 +0000 [INFO]: WebScriptInputHandler: Page already preloaded, not triggering preload again 2016-08-23 12:34:58 +0000 [INFO]: | webViewDidStartLoad: 2016-08-23 12:34:58 +0000 [INFO]: CrmViewController: webpage load started 2016-08-23 12:34:58 +0000 [VERBOSE]: WVCD: Loaded Url | about:blank 2016-08-23 12:34:58 +0000 [INFO]: Load finished 2016-08-23 12:34:58 +0000 [INFO]: Mark event (end without start) | PageLoad 2016-08-23 12:34:58 +0000 [INFO]: Mark event (end without start) | AppLaunch 2016-08-23 12:34:58 +0000 [INFO]: | webViewDidFinishLoad: 2016-08-23 12:34:58 +0000 [INFO]: CrmViewController: webpage load finished 2016-08-23 12:35:00 +0000 [INFO]: | appPause 2016-08-23 12:35:00 +0000 [INFO]: Application Pause 2016-08-23 12:35:00 +0000 [INFO]: Application pause 2016-08-23 12:35:00 +0000 [INFO]: WebScriptBridge: App pause 2016-08-23 12:35:00 +0000 [VERBOSE]: Start event | DispatchersAppPause 2016-08-23 12:35:00 +0000 [INFO]: End event | DispatchersAppPause, 0.028968 2016-08-23 12:35:00 +0000 [VERBOSE]: Start event | DevicePause 2016-08-23 12:35:00 +0000 [INFO]: End event | DevicePause, 0.566006 2016-08-23 12:35:01 +0000 [ERROR]: Telemetry Reporter: End Point Not Set. Dumping Event Data. | [{"ActivityId":"","EventName":"Session_Complete","EventValues":{"ActiveSessionTime":130.797613}}]
*This post is locked for comments
I have the same question (0)
Share
Report
All responses (
Answers (
2
