KB484312: Enable Info log for the ODBOConnector on an MDX cube provider to troubleshoot dossier execution issues

Starting with the release of Strategy ONE (March 2024), dossiers are also known as dashboards.

Introduction

You can enable Info log for the ODBOConnector on an MDX cube provider to troubleshoot dossier execution issues. The ODBOConnector logs are only available on the machine where the MDX cube provider is installed.
The log is useful in the following scenarios:

• The connection to the database fails
• Dossier return unusual errors including, SAX Parser Fatal Error, XMLA error. SAX parser is hang after HTTP transfer is finished, XMLA GetNextTable Failed.SOAP Error.
• Dossiers that continue running for a long period of time until request timeout.
• Any other scenario where you need to investigate the issue in the MDX cube provider

How to enable the log

• Execute Diagnostics Configuration from the Windows Start menu or execute madpcfg in the command line of the machine where the MDX cube provider is installed.
• Locate ODBOConnector > Info, click in the File Log column and choose New.
  

• Choose a name for the log file and save it.
  

• Click Save in the toolbar and close the window.
• Browse catalogs on Developer or Web.
• Find the new log files in the Log folder. The default location for this folder is C:\Program Files (x86)\Common Files\MicroStrategy\Log.
• Logs generated for successfully executed dossiers contain a large number of results.
  

  
  2020-05-28 22:24:34.978-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] XMLAOCI::executeMDXBinarySession: BEGIN
  2020-05-28 22:24:34.981-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] SessionManagerImpl::CreateSession: Begin
  2020-05-28 22:24:34.981-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] SessionManagerImpl::CreateSession: End
  2020-05-28 22:24:34.982-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] Session::Open: Begin; Connection string = [UID=TECH_SERVICES\TestUser2;Catalog=AdventureWorksDW;OLAPProvider=MSOLAP;AuthMode=2;LocaleIdentifier=;]
  2020-05-28 22:24:35.011-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] Session::InitializeDataSource: TestU = [TECH_SERVICES\TestUser2]
  2020-05-28 22:24:35.063-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] Set current catalog to AdventureWorksDW
  2020-05-28 22:24:35.064-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] Session::Open: End; Time elapsed = 82ms
  2020-05-28 22:24:35.069-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] Command::ExecuteQuery: Begin; MDX = with member [Measures].[FreeForm_0] as '1', SOLVE_ORDER = 127
  set [dim0_recursive_attr] as '{[Account].[Accounts].AllMembers}'
  set [dim1_recursive_attr] as '{[Geography].[Geography].AllMembers}'
  select {[Measures].[FreeForm_0]} on columns,
  non empty CROSSJOIN(hierarchize({[dim0_recursive_attr]}), hierarchize({[dim1_recursive_attr]})) dimension properties [Account].[Accounts].KEY0, MEMBER_KEY, MEMBER_NAME, [Geography].[Geography].KEY0 on rows
  from [Adventure Works]
  2020-05-28 22:24:36.883-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] Command::ExecuteQuery: End; Time elapsed = 1813ms
  2020-05-28 22:24:36.887-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] MDXResult::GetXMLBinaryWithSession: Begin
  2020-05-28 22:24:36.888-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] Encoded Name = _0x005B_Account_0x005D_._0x005B_Accounts_0x005D_._0x005B_KEY0_0x005D_
  2020-05-28 22:24:36.888-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] Encoded Name = _0x005B_Account_0x005D_._0x005B_Accounts_0x005D_._0x005B_MEMBER_KEY_0x005D_
  2020-05-28 22:24:36.888-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] Encoded Name = _0x005B_Account_0x005D_._0x005B_Accounts_0x005D_._0x005B_MEMBER_NAME_0x005D_
  2020-05-28 22:24:36.888-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] Encoded Name = _0x005B_Geography_0x005D_._0x005B_Geography_0x005D_._0x005B_MEMBER_KEY_0x005D_
  2020-05-28 22:24:36.888-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] Encoded Name = _0x005B_Geography_0x005D_._0x005B_Geography_0x005D_._0x005B_MEMBER_NAME_0x005D_
  2020-05-28 22:24:36.888-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] Encoded Name = _0x005B_Geography_0x005D_._0x005B_Geography_0x005D_._0x005B_KEY0_0x005D_
  2020-05-28 22:24:36.893-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] MDXResult::GetXMLBinaryWithSession: End; Time elapsed = 5ms
  2020-05-28 22:24:36.894-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] Fetching 1 rows
  2020-05-28 22:24:36.895-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] FetchRowsetTableNew; Fetch and table build = 0ms
  2020-05-28 22:24:36.895-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] MDXResultImpl::SerializeMemberDataTableSession : serialization time = 1ms
  2020-05-28 22:24:36.952-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] Fetching 120000 rows
  2020-05-28 22:24:38.108-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] FetchRowsetTableNew; Fetch and table build = 1212ms
  2020-05-28 22:24:39.140-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] MDXResultImpl::SerializeMemberDataTableSession : serialization time = 2244ms
  2020-05-28 22:24:39.189-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] Fetching 1 rows
  2020-05-28 22:24:39.214-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] FetchRowsetTableNew; Fetch and table build = 72ms
  2020-05-28 22:24:39.219-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] MDXResultImpl::SerializeMemberDataTableSession : serialization time = 78ms
  2020-05-28 22:24:39.221-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] Fetching 120000 rows
  2020-05-28 22:24:39.492-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] FetchRowsetTableNew; Fetch and table build = 271ms
  2020-05-28 22:24:39.580-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] MDXResultImpl::SerializeCellDataTableSession : serialization time = 359ms
  2020-05-28 22:24:39.585-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:7348][ODBOConnector][Info][UID:0][SID:0][OID:0] XMLAOCI::executeMDXBinarySession: END; Time elapsed = 4605ms
  2020-05-28 22:24:55.568-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:3472][ODBOConnector][Info][UID:0][SID:0][OID:0] XMLAOCI::ExecuteHandler::getNextTable: BEGIN
  2020-05-28 22:24:55.578-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:3472][ODBOConnector][Info][UID:0][SID:0][OID:0] Fetching 9789 rows
  2020-05-28 22:24:55.652-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:3472][ODBOConnector][Info][UID:0][SID:0][OID:0] FetchRowsetTableNew; Fetch and table build = 81ms
  2020-05-28 22:24:55.734-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:3472][ODBOConnector][Info][UID:0][SID:0][OID:0] MDXResultImpl::SerializeMemberDataTableSession : serialization time = 163ms
  2020-05-28 22:24:55.734-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:3472][ODBOConnector][Info][UID:0][SID:0][OID:0] Fetching 9789 rows
  2020-05-28 22:24:55.758-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:3472][ODBOConnector][Info][UID:0][SID:0][OID:0] FetchRowsetTableNew; Fetch and table build = 23ms
  2020-05-28 22:24:55.764-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:3472][ODBOConnector][Info][UID:0][SID:0][OID:0] MDXResultImpl::SerializeCellDataTableSession : serialization time = 30ms
  2020-05-28 22:24:55.765-04:00 [HOST:tec-w-010664][SERVER:][PID:9020][THR:3472][ODBOConnector][Info][UID:0][SID:0][OID:0] XMLAOCI::getNextTable: END; Time elapsed = 196ms