Community
cancel
Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for 
Search instead for 
Did you mean: 
Read only

EPM reporting running incredibly slowly

Former Member

on ‎2016 Feb 24 10:43 AM

0 Likes
2,691

Hi experts

EPM10 SP21

We have a problem in that Input Schedules and Reports are taking around 20 minutes to return data.  They should only be taking a minute or two...

Nothing has changed in the report although several new dimension members have been added.

So far, I have tried the following:

- Cleared dimension cache locally and on the server

- Re-processed dimensions

- Re-named PC_NW folder locally

- Checked statistics and index on cube - all perfect

- Stripped out all VB code, local members etc... Vanilla report still has delays

- Run UJSTAT report to see why report loaded in 13 minutes.  The outcome was a delay at RSDRI_INFOPROV_READ

I have a customer message in place as of this morning but am hoping to progress this before the system needs to be available next week !

Any pointers / advice will be very much appreciated

Regards

Ian

Know the answer?

Help others by sharing your knowledge.

Answer

Need more details?

Request clarification before answering.

Show replies
Show replies

Accepted Solutions (0)

Answers (5)

Answers (5)

Former Member
‎2016 Feb 26 11:59 AM
0 Likes

Hello guys

Thanks for the responses.  We are not using a Master Data on the Fly tool and we have looked into the meta data cache.  However, my understanding of the meta data cache has now improved and we are not using this (within user settings) so must be retrieving the latest data from the server each time.

We are currently looking at indexes and trying to re-create them assuming there may be some corruption.  The slow run-time for our report can be see below in the performance log.....  See the delays from 8:43 to about 09:10.  This normally only takes a minute.  The UJSTAT shows the following:

Thanks again

Ian

FILE_TYPE:DAAA96DE-B0FB-4c6e-AF7B-A445F5BF9BE2

RECORD_SEPARATOR:35

COLUMN_SEPARATOR:124

COLUMNS:Time|Severity|Category|Method|User|DSRRootContextID|DSRTransactionID|DSRConnectionID|DSRCounter|Thread|Text

SEVERITY_MAP:DEBUG|Debug|INFO|Information|VERBOSE|Verbose|WARNING|Warning|ERROR|Error|FATAL|Fatal

HEADER_END

2016-02-26 08:39:47,068|TRACE|Performance|FPMXLClient.Connect.OnConnection||||||1|Measure [EpmLoading] start#

2016-02-26 08:39:47,110|TRACE|Performance|FPMXLClient.Connect.OnConnection||||||1|Method [OnConnection] start#

2016-02-26 08:39:47,112|TRACE|Performance|FPMXLClient.Connect.OnConnection||||||1|Measure [Initialize automation API] start#

2016-02-26 08:39:47,119|TRACE|Performance|FPMXLClient.Connect.OnConnection||||||1|Measure [Initialize automation API] end, duration = 0.006 s#

2016-02-26 08:39:47,119|TRACE|Performance|FPMXLClient.Connect.OnConnection||||||1|Measure [Set global parameters] start#

2016-02-26 08:39:47,226|VERBOSE|Performance|FPMXLClient.Connect.OnConnection||||||1|Measure [ConnectionManager.LoadExtensions] start#

2016-02-26 08:39:47,234|TRACE|Performance|FPMXLClient.RibbonReader.ExtensionImporter..ctor||||||1|Measure [Load extensions.] start#

2016-02-26 08:39:47,677|TRACE|Performance|FPMXLClient.RibbonReader.ExtensionImporter..ctor||||||1|Measure [Load extensions.] end, duration = 0.436 s#

2016-02-26 08:39:47,681|VERBOSE|Performance|FPMXLClient.Connect.OnConnection||||||1|Measure [ConnectionManager.LoadExtensions] end, duration = 0.454 s#

2016-02-26 08:39:47,684|TRACE|Performance|FPMXLClient.Persistence.UserPreferenceHelperBase.Load||||||1|Measure [Load User Preferences] start#

2016-02-26 08:39:47,961|TRACE|Performance|FPMXLClient.Persistence.UserPreferenceHelperBase.Load||||||1|Measure [Load User Preferences] end, duration = 0.275 s#

2016-02-26 08:39:48,000|TRACE|Performance|FPMXLClient.Connect.OnConnection||||||1|Measure [Set global parameters] end, duration = 0.881 s#

2016-02-26 08:39:48,074|TRACE|Performance|FPMXLClient.Connect.BackgroundInitialize||||||1|Method [Background initialization] start#

2016-02-26 08:39:48,177|TRACE|Performance|FPMXLClient.RibbonReader.ExtensionImporter..ctor||||||1|Measure [Load extensions.] start#

2016-02-26 08:39:48,180|TRACE|Performance|FPMXLClient.RibbonReader.ExtensionImporter..ctor||||||1|Measure [Load extensions.] end, duration = 0.001 s#

2016-02-26 08:39:48,206|TRACE|Performance|FPMXLClient.Connect.BackgroundInitialize||||||1|Method [Background initialization] end, duration = 0.131 s#

2016-02-26 08:39:48,206|TRACE|Performance|FPMXLClient.Connect.OnConnection||||||1|Method [OnConnection] end, duration = 1.095 s#

2016-02-26 08:39:48,207|TRACE|Performance|FPMXLClient.Connect.CTPFactoryAvailable||||||1|Method [CTPFactoryAvailable] start#

2016-02-26 08:39:48,207|TRACE|Performance|FPMXLClient.Connect.CTPFactoryAvailable||||||1|Method [CTPFactoryAvailable] end, duration = 0.000 s#

2016-02-26 08:39:48,207|TRACE|Performance|FPMXLClient.Connect.GetCustomUI||||||1|Method [GetCustomUI] start#

2016-02-26 08:39:48,209|TRACE|Performance|FPMXLClient.RibbonManagement.RibbonImporter..ctor||||||1|Measure [Load Ribbon extensions] start#

2016-02-26 08:39:48,215|TRACE|Performance|FPMXLClient.RibbonManagement.RibbonImporter..ctor||||||1|Measure [Load Ribbon extensions] end, duration = 0.004 s#

2016-02-26 08:39:48,216|TRACE|Performance|FPMXLClient.RibbonReader.RibbonReader.GetCustomUI||||||1|Measure [Load ribbon elements] start#

2016-02-26 08:39:48,242|TRACE|Performance|FPMXLClient.RibbonReader.RibbonReader.GetCustomUI||||||1|Measure [Load ribbon elements] end, duration = 0.026 s#

2016-02-26 08:39:48,242|TRACE|Performance|FPMXLClient.RibbonReader.RibbonReader.GetCustomUI||||||1|Measure [Build XML ribbon] start#

2016-02-26 08:39:48,513|TRACE|Performance|FPMXLClient.RibbonReader.RibbonReader.GetCustomUI||||||1|Measure [Build XML ribbon] end, duration = 0.271 s#

2016-02-26 08:39:48,514|TRACE|Performance|FPMXLClient.Connect.GetCustomUI||||||1|Method [GetCustomUI] end, duration = 0.306 s#

2016-02-26 08:39:48,525|TRACE|Performance|FPMXLClient.Connect.RibbonOnLoad||||||1|Method [RibbonOnLoad] start#

2016-02-26 08:39:48,563|TRACE|Performance|FPMXLClient.Connect.RibbonOnLoad||||||1|Method [RibbonOnLoad] end, duration = 0.038 s#

2016-02-26 08:39:48,570|TRACE|Performance|FPMXLClient.Connect.OnStartupComplete||||||1|Measure [EpmLoading] end, duration = 1.474 s#

2016-02-26 08:39:52,794|TRACE|Performance|FPMXLClient.RibbonReader.ExtensionImporter..ctor||||||1|Measure [Load extensions.] start#

2016-02-26 08:39:53,008|TRACE|Performance|FPMXLClient.RibbonReader.ExtensionImporter..ctor||||||1|Measure [Load extensions.] end, duration = 0.211 s#

2016-02-26 08:39:56,296|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.BeforeSendRequest||||||6|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/session?method=Get] start#

2016-02-26 08:40:18,725|TRACE|Performance|FPMXLClient.Connection.ConnectionManager.CreateConnection||||||1|Measure [Create Connection on sapbwp.jcb.local JCB_DEV S_AND_OP ] start#

2016-02-26 08:40:18,760|TRACE|Performance|FPMXLClient.Connection.RESTConnection.Connect||||||1|Measure [Global connect time bpc ] start#

2016-02-26 08:40:18,992|TRACE|Performance|FPMXLClient.BPCProxy.Session.SessionServiceHelper.Logon||||||1|Method [Logon] start#

2016-02-26 08:40:18,996|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.BeforeSendRequest||||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/session?method=Get] start#

2016-02-26 08:40:19,035|TRACE|Performance|FPMXLClient.BPCProxy.Session.SessionServiceHelper.Logon||||||1|Method [Logon] end, duration = 0.042 s#

2016-02-26 08:40:19,036|TRACE|Performance|FPMXLClient.BPCProxy.Session.SessionServiceHelper.Logoff||||||1|Method [Logoff] start#

2016-02-26 08:40:19,037|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.BeforeSendRequest||||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/session?method=Logout] start#

2016-02-26 08:40:19,052|TRACE|Performance|FPMXLClient.BPCProxy.Session.SessionServiceHelper.Logoff||||||1|Method [Logoff] end, duration = 0.015 s#

2016-02-26 08:40:19,111|TRACE|Performance|FPMXLClient.Connection.RESTConnection.Connect||||||1|Measure [Global connect time bpc ] end, duration = 0.349 s#

2016-02-26 08:40:19,115|TRACE|Performance|FPMXLClient.Connection.ConnectionManager.CreateConnection||||||1|Measure [Create Connection on sapbwp.jcb.local JCB_DEV S_AND_OP ] end, duration = 0.375 s#

2016-02-26 08:40:24,840|TRACE|Performance|FPMXLClient.Connection.ConnectionManager.CreateConnection||||||1|Measure [Create Connection on sapbwp.jcb.local JCB_DEV S_AND_OP ] start#

2016-02-26 08:40:24,879|TRACE|Performance|FPMXLClient.Connection.RESTConnection.Connect||||||1|Measure [Global connect time bpc ] start#

2016-02-26 08:40:25,053|TRACE|Performance|FPMXLClient.BPCProxy.Session.SessionServiceHelper.Logon||||||1|Method [Logon] start#

2016-02-26 08:40:25,054|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.BeforeSendRequest||||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/session?method=Get] start#

2016-02-26 08:40:25,163|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.AfterReceiveReply||||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/session?method=Get] end, duration = 0.109 s#

2016-02-26 08:40:25,509|TRACE|Performance|FPMXLClient.BPCProxy.Session.SessionServiceHelper.Logon||||||1|Method [Logon] end, duration = 0.455 s#

2016-02-26 08:40:25,513|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.BeforeSendRequest||||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/updates?format=xml] start#

2016-02-26 08:40:25,557|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.AfterReceiveReply||||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/updates?format=xml] end, duration = 0.044 s#

2016-02-26 08:40:25,836|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.MetaDataWSExtHelper.GetAppSets||||||1|Method [GetAppSets] start#

2016-02-26 08:40:25,837|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.BeforeSendRequest||||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/appsets/?level=0] start#

2016-02-26 08:40:25,889|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.AfterReceiveReply||||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/appsets/?level=0] end, duration = 0.052 s#

2016-02-26 08:40:25,896|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.MetaDataWSExtHelper.GetAppSets||||||1|Method [GetAppSets] end, duration = 0.060 s#

2016-02-26 08:40:25,902|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.MetaDataWSExtHelper.GetApplicationsList||||||1|Method [GetApplicationsList] start, parameters:

    appsetId = JCB_DEV#

2016-02-26 08:40:25,903|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.BeforeSendRequest||||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/appsets/JCB_DEV/?level=1] start#

2016-02-26 08:40:26,202|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.AfterReceiveReply||||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/appsets/JCB_DEV/?level=1] end, duration = 0.299 s#

2016-02-26 08:40:26,281|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.MetaDataWSExtHelper.GetApplicationsList||||||1|Method [GetApplicationsList] end, duration = 0.378 s#

2016-02-26 08:40:26,282|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.BeforeSendRequest||||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/drillthroughs/JCB_DEV] start#

2016-02-26 08:40:26,332|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.AfterReceiveReply||||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/drillthroughs/JCB_DEV] end, duration = 0.049 s#

2016-02-26 08:40:26,368|TRACE|Performance|FPMXLClient.Connection.RESTConnection.Connect||||||1|Measure [Global connect time bpc ] end, duration = 1.487 s#

2016-02-26 08:40:26,370|TRACE|Performance|FPMXLClient.Connection.ConnectionManager.LoadMetaData||||||1|Method [Global LoadMetaData] start#

2016-02-26 08:40:26,375|TRACE|Performance|FPMXLClient.Connection.ConnectionImpl.LoadMetaData||||||1|Method [LoadMetaData ConnectionImpl] start#

2016-02-26 08:40:26,417|TRACE|Performance|FPMXLClient.Persistence.UserPreferenceHelperBase.Load||||||1|Measure [Load User Preferences] start#

2016-02-26 08:40:26,419|TRACE|Performance|FPMXLClient.Persistence.UserPreferenceHelperBase.Load||||||1|Measure [Load User Preferences] end, duration = 0.002 s#

2016-02-26 08:40:26,424|TRACE|Performance|FPMXLClient.Connection.ConnectionImpl.LoadMetaData||||||1|Measure [Loading meta data from server for app S_AND_OP] start#

2016-02-26 08:40:26,798|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.BeforeSendRequest||||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/applications/JCB_DEV/S_AND_OP?format=csv&level=0&keydate=2016-0...] start#

2016-02-26 08:40:27,552|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.AfterReceiveReply||||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/applications/JCB_DEV/S_AND_OP?format=csv&level=0&keydate=2016-0...] end, duration = 0.751 s#

2016-02-26 08:40:27,626|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension ANALYSIS] start#

2016-02-26 08:40:27,634|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension ANALYSIS] end, duration = 0.006 s#

2016-02-26 08:40:27,634|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension ANALYSIS] start#

2016-02-26 08:40:27,635|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension ANALYSIS] end, duration = 0.000 s#

2016-02-26 08:40:27,668|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension BRAND] start#

2016-02-26 08:40:27,686|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension BRAND] end, duration = 0.017 s#

2016-02-26 08:40:27,687|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension BRAND] start#

2016-02-26 08:40:27,687|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension BRAND] end, duration = 0.000 s#

2016-02-26 08:40:27,738|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension CATEGORY] start#

2016-02-26 08:40:27,740|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension CATEGORY] end, duration = 0.002 s#

2016-02-26 08:40:27,740|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension CATEGORY] start#

2016-02-26 08:40:27,741|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension CATEGORY] end, duration = 0.000 s#

2016-02-26 08:40:27,778|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension CUSTOMER] start#

2016-02-26 08:40:27,986|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension CUSTOMER] end, duration = 0.207 s#

2016-02-26 08:40:27,987|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension CUSTOMER] start#

2016-02-26 08:40:28,052|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension CUSTOMER] end, duration = 0.065 s#

2016-02-26 08:40:28,066|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension DATASRC] start#

2016-02-26 08:40:28,068|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension DATASRC] end, duration = 0.002 s#

2016-02-26 08:40:28,068|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension DATASRC] start#

2016-02-26 08:40:28,069|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension DATASRC] end, duration = 0.000 s#

2016-02-26 08:40:28,075|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension INDUSTRY] start#

2016-02-26 08:40:28,076|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension INDUSTRY] end, duration = 0.000 s#

2016-02-26 08:40:28,077|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension INDUSTRY] start#

2016-02-26 08:40:28,077|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension INDUSTRY] end, duration = 0.000 s#

2016-02-26 08:40:28,095|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension METRIC] start#

2016-02-26 08:40:28,097|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension METRIC] end, duration = 0.001 s#

2016-02-26 08:40:28,097|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension METRIC] start#

2016-02-26 08:40:28,098|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension METRIC] end, duration = 0.000 s#

2016-02-26 08:40:28,148|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension PRODUCT] start#

2016-02-26 08:40:28,270|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension PRODUCT] end, duration = 0.121 s#

2016-02-26 08:40:28,271|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension PRODUCT] start#

2016-02-26 08:40:28,288|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension PRODUCT] end, duration = 0.017 s#

2016-02-26 08:40:28,299|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension RPTCURRENCY] start#

2016-02-26 08:40:28,300|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension RPTCURRENCY] end, duration = 0.000 s#

2016-02-26 08:40:28,301|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension RPTCURRENCY] start#

2016-02-26 08:40:28,301|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension RPTCURRENCY] end, duration = 0.000 s#

2016-02-26 08:40:28,309|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension SUPPLY_SITE] start#

2016-02-26 08:40:28,310|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension SUPPLY_SITE] end, duration = 0.000 s#

2016-02-26 08:40:28,311|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension SUPPLY_SITE] start#

2016-02-26 08:40:28,311|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension SUPPLY_SITE] end, duration = 0.000 s#

2016-02-26 08:40:28,350|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension TIME] start#

2016-02-26 08:40:28,359|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension TIME] end, duration = 0.008 s#

2016-02-26 08:40:28,360|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension TIME] start#

2016-02-26 08:40:28,361|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension TIME] end, duration = 0.001 s#

2016-02-26 08:40:28,364|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension MEASURES] start#

2016-02-26 08:40:28,365|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Read members of dimension MEASURES] end, duration = 0.000 s#

2016-02-26 08:40:28,365|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension MEASURES] start#

2016-02-26 08:40:28,366|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ReadDimensionAndMembersNormal||||||1|Measure [Construct hierarchies for dimension MEASURES] end, duration = 0.000 s#

2016-02-26 08:40:28,379|TRACE|Performance|FPMXLClient.Connection.ConnectionImpl.LoadMetaData||||||1|Measure [Loading meta data from server for app S_AND_OP] end, duration = 1.954 s#

2016-02-26 08:40:28,467|TRACE|Performance|FPMXLClient.Connection.ConnectionImpl.LoadMetaData||||||1|Method [LoadMetaData ConnectionImpl] end, duration = 2.090 s#

2016-02-26 08:40:28,468|TRACE|Performance|FPMXLClient.Connection.ConnectionManager.LoadMetaData||||||1|Method [Global LoadMetaData] end, duration = 2.097 s#

2016-02-26 08:40:28,469|TRACE|Performance|FPMXLClient.Connection.ConnectionManager.CreateConnection||||||1|Measure [Global On connection change] start#

2016-02-26 08:40:28,471|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache||||||1|Method [ClearXllCache] start#

2016-02-26 08:40:28,472|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache||||||1|Method [ClearXllCache] end, duration = 0.000 s#

2016-02-26 08:40:28,473|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache||||||1|Method [ClearXllCache] start#

2016-02-26 08:40:28,473|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache||||||1|Method [ClearXllCache] end, duration = 0.000 s#

2016-02-26 08:40:28,481|TRACE|Performance|FPMXLClient.ExcelEvents.xlAppEvents_SheetSelectionChangeWithoutCatch||||||1|Measure [ForceActiveConnectionChanged] start#

2016-02-26 08:40:28,483|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] start#

2016-02-26 08:40:28,483|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] end, duration = 0.000 s#

2016-02-26 08:40:28,484|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] start#

2016-02-26 08:40:28,484|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] end, duration = 0.000 s#

2016-02-26 08:40:28,497|TRACE|Performance|FPMXLClient.ExcelEvents.xlAppEvents_SheetSelectionChangeWithoutCatch|mannioni|||||1|Measure [ForceActiveConnectionChanged] end, duration = 0.015 s#

2016-02-26 08:40:28,523|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] start#

2016-02-26 08:40:28,524|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] end, duration = 0.000 s#

2016-02-26 08:40:28,525|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] start#

2016-02-26 08:40:28,525|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] end, duration = 0.000 s#

2016-02-26 08:40:28,526|TRACE|Performance|FPMXLClient.Connection.ConnectionManager.CreateConnection|mannioni|||||1|Measure [Global On connection change] end, duration = 0.057 s#

2016-02-26 08:40:28,539|TRACE|Performance|FPMXLClient.Connection.ConnectionManager.CreateConnection|mannioni|||||1|Measure [Create Connection on sapbwp.jcb.local JCB_DEV S_AND_OP ] end, duration = 3.697 s#

2016-02-26 08:41:09,476|TRACE|Performance|FPMXLClient.BPCProxy.FilterData.FileServiceHelper.ListFolder|mannioni|||||1|Method [ListFolder] start, parameters:

    request = FPMXLClient.BPCProxy.FileService.GetFileRequest#

2016-02-26 08:41:09,480|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.BeforeSendRequest|mannioni|||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/files?method=list] start#

2016-02-26 08:41:09,630|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.AfterReceiveReply|mannioni|||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/files?method=list] end, duration = 0.149 s#

2016-02-26 08:41:09,659|TRACE|Performance|FPMXLClient.BPCProxy.FilterData.FileServiceHelper.ListFolder|mannioni|||||1|Method [ListFolder] end, duration = 0.178 s#

2016-02-26 08:41:09,677|TRACE|Performance|FPMXLClient.BPCProxy.FilterData.FileServiceHelper.ListFolder|mannioni|||||1|Method [ListFolder] start, parameters:

    request = FPMXLClient.BPCProxy.FileService.GetFileRequest#

2016-02-26 08:41:09,679|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.BeforeSendRequest|mannioni|||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/files?method=list] start#

2016-02-26 08:41:09,768|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.AfterReceiveReply|mannioni|||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/files?method=list] end, duration = 0.088 s#

2016-02-26 08:41:09,771|TRACE|Performance|FPMXLClient.BPCProxy.FilterData.FileServiceHelper.ListFolder|mannioni|||||1|Method [ListFolder] end, duration = 0.093 s#

2016-02-26 08:41:32,919|TRACE|Performance|FPMXLClient.BPCProxy.FilterData.FileServiceHelper.ListFolder|mannioni|||||1|Method [ListFolder] start, parameters:

    request = FPMXLClient.BPCProxy.FileService.GetFileRequest#

2016-02-26 08:41:32,921|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.BeforeSendRequest|mannioni|||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/files?method=list] start#

2016-02-26 08:41:33,151|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.AfterReceiveReply|mannioni|||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/files?method=list] end, duration = 0.228 s#

2016-02-26 08:41:33,153|TRACE|Performance|FPMXLClient.BPCProxy.FilterData.FileServiceHelper.ListFolder|mannioni|||||1|Method [ListFolder] end, duration = 0.232 s#

2016-02-26 08:41:33,639|TRACE|Performance|FPMXLClient.BPCProxy.FilterData.FileServiceHelper.ListFolder|mannioni|||||1|Method [ListFolder] start, parameters:

    request = FPMXLClient.BPCProxy.FileService.GetFileRequest#

2016-02-26 08:41:33,641|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.BeforeSendRequest|mannioni|||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/files?method=list] start#

2016-02-26 08:41:33,720|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.AfterReceiveReply|mannioni|||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/files?method=list] end, duration = 0.078 s#

2016-02-26 08:41:33,723|TRACE|Performance|FPMXLClient.BPCProxy.FilterData.FileServiceHelper.ListFolder|mannioni|||||1|Method [ListFolder] end, duration = 0.082 s#

2016-02-26 08:41:38,053|TRACE|Performance|FPMXLClient.BPCProxy.FilterData.FileServiceHelper.GetFullPath|mannioni|||||1|Method [GetFullPath] start, parameters:

    request = FPMXLClient.BPCProxy.FileService.GetFileRequest#

2016-02-26 08:41:38,060|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.BeforeSendRequest|mannioni|||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/files?method=getfullpath] start#

2016-02-26 08:41:38,121|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.AfterReceiveReply|mannioni|||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/files?method=getfullpath] end, duration = 0.060 s#

2016-02-26 08:41:38,123|TRACE|Performance|FPMXLClient.BPCProxy.FilterData.FileServiceHelper.GetFullPath|mannioni|||||1|Method [GetFullPath] end, duration = 0.064 s#

2016-02-26 08:41:38,196|TRACE|Performance|FPMXLClient.BPCProxy.FilterData.FileServiceHelper.Get|mannioni|||||1|Method [Get] start, parameters:

    returnFileContent = True

    zipFile = False

    request = FPMXLClient.BPCProxy.FileService.GetFileRequest#

2016-02-26 08:41:38,196|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.BeforeSendRequest|mannioni|||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/files?method=get&return_file_content=true&zip_file=false] start#

2016-02-26 08:41:40,573|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.AfterReceiveReply|mannioni|||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/files?method=get&return_file_content=true&zip_file=false] end, duration = 2.376 s#

2016-02-26 08:41:40,839|TRACE|Performance|FPMXLClient.BPCProxy.FilterData.FileServiceHelper.Get|mannioni|||||1|Method [Get] end, duration = 2.643 s#

2016-02-26 08:41:41,566|TRACE|Performance|FPMXLClient.Office.OpenSaveExcelManager.OpenLocalFile|mannioni|||||1|Method [OpenLocalFile DMD-INP-DM07-01-230216_011.xltm] start#

2016-02-26 08:41:41,568|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.OpenWorkbook|mannioni|||||1|Method [OpenWorkbook Excel API] start#

2016-02-26 08:41:57,034|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.OpenWorkbook|mannioni|||||1|Method [OpenWorkbook Excel API] end, duration = 15.465 s#

2016-02-26 08:41:57,035|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.TransportConnectionDescriptor|mannioni|||||1|Method [TransportConnectionDescriptor] start#

2016-02-26 08:41:57,036|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.TransportConnectionDescriptorForOneSheet|mannioni|||||1|Method [TransportConnectionDescriptorForOneSheet EPMFormattingSheet] start#

2016-02-26 08:41:57,052|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.TransportConnectionDescriptorForOneSheet|mannioni|||||1|Method [TransportConnectionDescriptorForOneSheet EPMFormattingSheet] end, duration = 0.015 s#

2016-02-26 08:41:57,052|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.TransportConnectionDescriptorForOneSheet|mannioni|||||1|Method [TransportConnectionDescriptorForOneSheet T] start#

2016-02-26 08:41:57,054|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.TransportConnectionDescriptorForOneSheet|mannioni|||||1|Method [TransportConnectionDescriptorForOneSheet T] end, duration = 0.000 s#

2016-02-26 08:41:57,054|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.TransportConnectionDescriptorForOneSheet|mannioni|||||1|Method [TransportConnectionDescriptorForOneSheet INPUT] start#

2016-02-26 08:41:57,356|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.TransportConnectionDescriptorForOneSheet|mannioni|||||1|Method [TransportConnectionDescriptorForOneSheet INPUT] end, duration = 0.302 s#

2016-02-26 08:41:57,356|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.TransportConnectionDescriptorForOneSheet|mannioni|||||1|Method [TransportConnectionDescriptorForOneSheet Summary (PL)] start#

2016-02-26 08:41:57,370|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.TransportConnectionDescriptorForOneSheet|mannioni|||||1|Method [TransportConnectionDescriptorForOneSheet Summary (PL)] end, duration = 0.013 s#

2016-02-26 08:41:57,371|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.TransportConnectionDescriptorForOneSheet|mannioni|||||1|Method [TransportConnectionDescriptorForOneSheet Summary (PG)] start#

2016-02-26 08:41:57,402|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.TransportConnectionDescriptorForOneSheet|mannioni|||||1|Method [TransportConnectionDescriptorForOneSheet Summary (PG)] end, duration = 0.031 s#

2016-02-26 08:41:57,403|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.TransportConnectionDescriptorForOneSheet|mannioni|||||1|Method [TransportConnectionDescriptorForOneSheet NOTES] start#

2016-02-26 08:41:57,404|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.TransportConnectionDescriptorForOneSheet|mannioni|||||1|Method [TransportConnectionDescriptorForOneSheet NOTES] end, duration = 0.001 s#

2016-02-26 08:41:57,404|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.TransportConnectionDescriptorForOneSheet|mannioni|||||1|Method [TransportConnectionDescriptorForOneSheet SHOW] start#

2016-02-26 08:41:57,405|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.TransportConnectionDescriptorForOneSheet|mannioni|||||1|Method [TransportConnectionDescriptorForOneSheet SHOW] end, duration = 0.000 s#

2016-02-26 08:41:57,405|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.TransportConnectionDescriptorForOneSheet|mannioni|||||1|Method [TransportConnectionDescriptorForOneSheet OS] start#

2016-02-26 08:41:57,415|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.TransportConnectionDescriptorForOneSheet|mannioni|||||1|Method [TransportConnectionDescriptorForOneSheet OS] end, duration = 0.009 s#

2016-02-26 08:41:57,416|TRACE|Performance|FPMXLClient.Office.ExcelOfficeFile.TransportConnectionDescriptor|mannioni|||||1|Method [TransportConnectionDescriptor] end, duration = 0.380 s#

2016-02-26 08:41:57,418|TRACE|Performance|FPMXLClient.ExcelEvents.xlAppEvents_WorkbookOpen|mannioni|||||1|Method [xlAppEvents_WorkbookOpen] start#

2016-02-26 08:41:57,419|TRACE|Performance|FPMXLClient.ExcelEvents.xlAppEvents_WorkbookOpen|mannioni|||||1|Method [WorkbookOpen EpmAddIn handler] start#

2016-02-26 08:41:57,449|TRACE|Performance|FPMXLClient.AddIn.ConnectAllSheets|mannioni|||||1|Measure [Check if All Empty] start#

2016-02-26 08:41:57,450|TRACE|Performance|FPMXLClient.AddIn.ConnectAllSheets|mannioni|||||1|Measure [Check if All Empty] end, duration = 0.000 s#

2016-02-26 08:41:57,680|TRACE|Performance|FPMXLClient.ExcelEvents.xlAppEvents_WorkbookOpen|mannioni|||||1|Method [WorkbookOpen EpmAddIn handler] end, duration = 0.261 s#

2016-02-26 08:41:57,680|TRACE|Performance|FPMXLClient.Office.VbaEventCaller.OpenWorkbook|mannioni|||||1|Method [OpenWorkbook VBA Caller] start#

2016-02-26 08:41:57,682|TRACE|Performance|FPMXLClient.Office.VbaEventCaller.OpenWorkbook|mannioni|||||1|Method [OpenWorkbook VBA Caller] end, duration = 0.001 s#

2016-02-26 08:41:57,698|TRACE|Performance|FPMXLClient.ExcelEvents.xlAppEvents_WorkbookOpen|mannioni|||||1|Method [xlAppEvents_WorkbookOpen] end, duration = 0.280 s#

2016-02-26 08:41:57,700|TRACE|Performance|FPMXLClient.Office.TaskPaneManager.CreateCustomTaskPanes|mannioni|||||1|Method [CreateCustomTaskPanes] start#

2016-02-26 08:41:58,200|TRACE|Performance|FPMXLClient.Office.TaskPaneManager.CreateCustomTaskPanes|mannioni|||||1|Method [CreateCustomTaskPanes] end, duration = 0.500 s#

2016-02-26 08:41:58,240|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] start#

2016-02-26 08:41:58,241|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] end, duration = 0.000 s#

2016-02-26 08:41:58,241|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] start#

2016-02-26 08:41:58,241|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] end, duration = 0.000 s#

2016-02-26 08:41:58,261|TRACE|Performance|FPMXLClient.Office.OpenSaveExcelManager.OpenLocalFile|mannioni|||||1|Method [OpenLocalFile DMD-INP-DM07-01-230216_011.xltm] end, duration = 16.694 s#

2016-02-26 08:42:05,245|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 08:42:05,312|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.063 s#

2016-02-26 08:42:05,322|TRACE|Performance|FPMXLClient.ExcelEvents.xlAppEvents_SheetSelectionChangeWithoutCatch|mannioni|||||1|Measure [ForceActiveConnectionChanged] start#

2016-02-26 08:42:05,323|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] start#

2016-02-26 08:42:05,323|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] end, duration = 0.000 s#

2016-02-26 08:42:05,323|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] start#

2016-02-26 08:42:05,323|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] end, duration = 0.000 s#

2016-02-26 08:42:05,325|TRACE|Performance|FPMXLClient.Persistence.UserPreferenceHelperBase.Load|mannioni|||||1|Measure [Load User Preferences] start#

2016-02-26 08:42:05,328|TRACE|Performance|FPMXLClient.Persistence.UserPreferenceHelperBase.Load|mannioni|||||1|Measure [Load User Preferences] end, duration = 0.002 s#

2016-02-26 08:42:05,359|TRACE|Performance|FPMXLClient.ExcelEvents.xlAppEvents_SheetSelectionChangeWithoutCatch|mannioni|||||1|Measure [ForceActiveConnectionChanged] end, duration = 0.037 s#

2016-02-26 08:42:05,389|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 08:42:05,413|TRACE|Performance|FPMXLClient.ReportManagement.ScanningSession.InitNewSession|mannioni|||||1|Method [InitNewSession] start#

2016-02-26 08:42:05,989|TRACE|Performance|FPMXLClient.ReportManagement.ScanningSession.InitNewSession|mannioni|||||1|Method [InitNewSession] end, duration = 0.573 s#

2016-02-26 08:42:06,000|TRACE|Performance|FPMXLClient.ReportManagement.SheetReport.BuildFromScan|mannioni|||||1|Measure [Build from scan] start#

2016-02-26 08:42:06,008|TRACE|Performance|FPMXLClient.ReportManagement.SheetReport.ScanSheet|mannioni|||||1|Measure [Scanning sheet] start#

2016-02-26 08:42:08,107|TRACE|Performance|FPMXLClient.ReportManagement.SheetReport.ScanSheet|mannioni|||||1|Measure [Scanning sheet] end, duration = 2.098 s#

2016-02-26 08:42:08,107|TRACE|Performance|FPMXLClient.ReportManagement.SheetReport.BuildFromScan|mannioni|||||1|Measure [Build from scan] end, duration = 2.105 s#

2016-02-26 08:42:08,274|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 2.885 s#

2016-02-26 08:42:08,290|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 08:42:08,291|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.000 s#

2016-02-26 08:42:09,155|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 08:42:09,156|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.000 s#

2016-02-26 08:42:09,157|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 08:42:09,157|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.000 s#

2016-02-26 08:42:09,464|TRACE|Performance|FPMXLClient.UILayer.Forms.ReportManagerMemberSelectorDialog.InitializeParametrized|mannioni|||||1|Measure [Open member selector] start#

2016-02-26 08:42:09,919|TRACE|Performance|FPMXLClient.UILayer.Forms.ReportManagerMemberSelectorDialog.InitializeParametrized|mannioni|||||1|Measure [Open member selector] end, duration = 0.454 s#

2016-02-26 08:42:10,245|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property GROUP from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\91b46f0bd07bf0383f6b3665a52781bd.data] start#

2016-02-26 08:42:10,298|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property GROUP from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\91b46f0bd07bf0383f6b3665a52781bd.data] end, duration = 0.051 s#

2016-02-26 08:42:10,306|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property CALC from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\c84234a8f5a0c3f867329db47b5eb2a3.data] start#

2016-02-26 08:42:10,330|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property CALC from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\c84234a8f5a0c3f867329db47b5eb2a3.data] end, duration = 0.024 s#

2016-02-26 08:42:10,331|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property DMD_FST_RPT from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\a79db98e6fc2d3d662d34e866d926bd0.data] start#

2016-02-26 08:42:10,333|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property DMD_FST_RPT from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\a79db98e6fc2d3d662d34e866d926bd0.data] end, duration = 0.001 s#

2016-02-26 08:42:16,066|TRACE|Performance|FPMXLClient.Office.VbaEventCaller.BeforeCurrentViewChange|mannioni|||||1|Method [BeforeCurrentViewChange VBA Caller] start#

2016-02-26 08:42:16,067|TRACE|Performance|FPMXLClient.Office.VbaEventCaller.BeforeCurrentViewChange|mannioni|||||1|Method [BeforeCurrentViewChange VBA Caller] end, duration = 0.001 s#

2016-02-26 08:42:16,255|TRACE|Performance|FPMXLClient.Office.VbaEventCaller.AfterCurrentViewChange|mannioni|||||1|Method [AfterCurrentViewChange VBA Caller] start#

2016-02-26 08:42:16,903|DEBUG|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Method [RefreshSheetWithScan] start#

2016-02-26 08:42:16,997|TRACE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Measure [Refresh sheet INPUT] start#

2016-02-26 08:42:16,997|VERBOSE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Measure [Warning in case of big report management] start#

2016-02-26 08:42:17,030|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property GROUP from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\7c4cb8d14742d960381543e37b21178b.data] start#

2016-02-26 08:42:17,060|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property GROUP from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\7c4cb8d14742d960381543e37b21178b.data] end, duration = 0.026 s#

2016-02-26 08:42:17,062|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property CALC from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\01b906600300aa202044739a70d4b5c3.data] start#

2016-02-26 08:42:17,080|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property CALC from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\01b906600300aa202044739a70d4b5c3.data] end, duration = 0.018 s#

2016-02-26 08:42:17,082|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property ZM002 from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\47f5bd066913e6d00c74ac4d847ecf52.data] start#

2016-02-26 08:42:17,085|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property ZM002 from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\47f5bd066913e6d00c74ac4d847ecf52.data] end, duration = 0.002 s#

2016-02-26 08:42:17,121|TRACE|Performance|FPMXLClient.ReportManagement.ExpandedAxis.ApplyDynamicFiltering|mannioni|||||1|Measure [Apply filtering new method] start#

2016-02-26 08:42:17,135|TRACE|Performance|FPMXLClient.ReportManagement.ExpandedAxis.ApplyDynamicFiltering|mannioni|||||1|Measure [Apply filtering new method] end, duration = 0.013 s#

2016-02-26 08:42:17,147|VERBOSE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Measure [Warning in case of big report management] end, duration = 0.149 s#

2016-02-26 08:42:17,147|TRACE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Measure [Undo management] start#

2016-02-26 08:42:17,184|TRACE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Measure [Undo management] end, duration = 0.036 s#

2016-02-26 08:42:17,185|TRACE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Method [Loading box management] start#

2016-02-26 08:42:17,217|TRACE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Method [Loading box management] end, duration = 0.032 s#

2016-02-26 08:42:17,218|TRACE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Method [Refresh Document] start#

2016-02-26 08:42:17,223|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [DoRefreshDocument] start#

2016-02-26 08:42:17,228|TRACE|Performance|FPMXLClient.Office.VbaEventCaller.BeforeRefresh|mannioni|||||1|Method [BeforeRefresh VBA Caller] start#

2016-02-26 08:42:17,229|TRACE|Performance|FPMXLClient.Office.VbaEventCaller.BeforeRefresh|mannioni|||||1|Method [BeforeRefresh VBA Caller] end, duration = 0.000 s#

2016-02-26 08:42:17,230|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.ClearAllCachesToGet|mannioni|||||1|Method [ClearAllCachesToGet] start#

2016-02-26 08:42:17,234|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.ClearAllCachesToGet|mannioni|||||1|Method [ClearAllCachesToGet] end, duration = 0.004 s#

2016-02-26 08:42:17,234|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [Check if the worksheet is an EpmSheet] start#

2016-02-26 08:42:17,235|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [Check if the worksheet is an EpmSheet] end, duration = 0.000 s#

2016-02-26 08:42:17,236|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DeactivateXLLCommentsAndData|mannioni|||||1|Method [DeactivateXLLCommentsAndData] start#

2016-02-26 08:42:17,237|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DeactivateXLLCommentsAndData|mannioni|||||1|Method [DeactivateXLLCommentsAndData] end, duration = 0.000 s#

2016-02-26 08:42:17,238|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.CheckIfSomeReportsContainsCellReferences|mannioni|||||1|Method [CheckIfSomeReportsContainsCellReferences] start#

2016-02-26 08:42:17,246|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 08:42:17,246|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.000 s#

2016-02-26 08:42:17,251|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.CheckIfSomeReportsContainsCellReferences|mannioni|||||1|Method [CheckIfSomeReportsContainsCellReferences] end, duration = 0.006 s#

2016-02-26 08:42:17,257|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.ContainsEVFunctions|mannioni|||||1|Method [ContainsEVFunctions] start#

2016-02-26 08:42:17,259|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.ContainsEVFunctions|mannioni|||||1|Method [ContainsEVFunctions] end, duration = 0.002 s#

2016-02-26 08:42:17,260|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [Calculate formulas involved in Report] start#

2016-02-26 08:42:17,261|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.CalculateCacheFunction|mannioni|||||1|Method [CalculateCacheFunction] start#

2016-02-26 08:42:17,262|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.ForceCalculationOnSheet|mannioni|||||1|Method [ForceCalculationOnSheet(INPUT)] start#

2016-02-26 08:42:17,268|TRACE|Performance|FPMXLClient.Office.CellBasedFunctionStatusNamedRange.AfterSheetCalculation|mannioni|||||1|Method [AfterSheetCalculation] start#

2016-02-26 08:42:17,423|TRACE|Performance|FPMXLClient.Office.CellBasedFunctionStatusNamedRange.AfterSheetCalculation|mannioni|||||1|Method [AfterSheetCalculation] end, duration = 0.154 s#

2016-02-26 08:42:17,423|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.ForceCalculationOnSheet|mannioni|||||1|Method [ForceCalculationOnSheet(INPUT)] end, duration = 0.161 s#

2016-02-26 08:42:17,424|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.CalculateCacheFunction|mannioni|||||1|Method [CalculateCacheFunction] end, duration = 0.163 s#

2016-02-26 08:42:17,424|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [Calculate formulas involved in Report] end, duration = 0.164 s#

2016-02-26 08:42:17,430|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshSheet|mannioni|||||1|Method [RefreshSheet] start#

2016-02-26 08:42:17,431|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshSheet|mannioni|||||1|Measure [Check HasAddedMembers] start#

2016-02-26 08:42:17,432|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshSheet|mannioni|||||1|Measure [Check HasAddedMembers] end, duration = 0.001 s#

2016-02-26 08:42:17,441|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 08:42:17,442|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.000 s#

2016-02-26 08:42:17,446|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 08:42:17,446|TRACE|Performance|FPMXLClient.ReportManagement.ScanningSession.InitNewSession|mannioni|||||1|Method [InitNewSession] start#

2016-02-26 08:42:18,053|TRACE|Performance|FPMXLClient.ReportManagement.ScanningSession.InitNewSession|mannioni|||||1|Method [InitNewSession] end, duration = 0.606 s#

2016-02-26 08:42:18,056|TRACE|Performance|FPMXLClient.ReportManagement.SheetReport.BuildFromScan|mannioni|||||1|Measure [Build from scan] start#

2016-02-26 08:42:18,056|TRACE|Performance|FPMXLClient.ReportManagement.SheetReport.ScanSheet|mannioni|||||1|Measure [Scanning sheet] start#

2016-02-26 08:42:20,974|TRACE|Performance|FPMXLClient.ReportManagement.SheetReport.ScanSheet|mannioni|||||1|Measure [Scanning sheet] end, duration = 2.917 s#

2016-02-26 08:42:20,975|TRACE|Performance|FPMXLClient.ReportManagement.SheetReport.BuildFromScan|mannioni|||||1|Measure [Build from scan] end, duration = 2.919 s#

2016-02-26 08:42:20,985|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 3.539 s#

2016-02-26 08:42:20,991|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 08:42:20,992|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.000 s#

2016-02-26 08:42:21,014|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.RefreshReports|mannioni|||||1|Method [RefreshReports] start#

2016-02-26 08:42:21,031|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.ExecuteParallelizedReportsFramework35|mannioni|||||1|Measure [Parallelized Execution] start#

2016-02-26 08:42:21,033|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.ExecuteParallelizedReportsFramework35|mannioni|||||1|Measure [Parallelized Execution] end, duration = 0.001 s#

2016-02-26 08:42:21,034|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.ExecuteReport|mannioni|||||6|Measure [Execute Report000] start#

2016-02-26 08:42:21,044|TRACE|Performance|FPMXLClient.Office.EPMReportManager.ExecuteReportThreadStarter|mannioni|||||6|Method [ExecuteReportThreadStarter] start#

2016-02-26 08:42:21,046|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ExecuteReport|mannioni|||||6|Method [ExecuteReport] start#

2016-02-26 08:42:21,049|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.DataServiceHelper.ExecuteReportAsGzipStream|mannioni|||||6|Method [ExecuteReportAsStream] start#

2016-02-26 08:42:21,050|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.DataServiceHelper.ExecuteReportAsGzipStream|mannioni|||||6|Measure [Building Axis Request] start#

2016-02-26 08:42:21,050|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.JsonQueryAxisBuilder.BuildAxisQuery|mannioni|||||6|Method [Build AxisQuery As Stream] start#

2016-02-26 08:42:21,078|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.JsonQueryAxisBuilder.WriteSymmetricAxis|mannioni|||||6|Method [Build Symmetric Axis as Stream] start#

2016-02-26 08:42:21,081|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.JsonQueryAxisBuilder.WriteSymmetricAxis|mannioni|||||6|Method [Build Symmetric Axis as Stream] end, duration = 0.002 s#

2016-02-26 08:42:21,130|TRACE|Performance|FPMXLClient.ReportManagement.ExpandedAxis.ApplyDynamicFiltering|mannioni|||||6|Measure [Apply filtering new method] start#

2016-02-26 08:42:21,132|TRACE|Performance|FPMXLClient.ReportManagement.ExpandedAxis.ApplyDynamicFiltering|mannioni|||||6|Measure [Apply filtering new method] end, duration = 0.002 s#

2016-02-26 08:42:21,171|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.JsonQueryAxisBuilder.BuildAxisQuery|mannioni|||||6|Method [Build AxisQuery As Stream] end, duration = 0.120 s#

2016-02-26 08:42:21,172|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.DataServiceHelper.ExecuteReportAsGzipStream|mannioni|||||6|Measure [Building Axis Request] end, duration = 0.122 s#

2016-02-26 08:42:21,173|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.DataServiceHelper.AxisQueryStreamEx|mannioni|||||6|Method [AxisQueryStreamEx] start, parameters:

    name = JCB_DEV

    parameters = S_AND_OP#

2016-02-26 08:42:21,173|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.BeforeSendRequest|mannioni|||||6|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/query/JCB_DEV/S_AND_OP?method=axis] start#

2016-02-26 08:42:39,684|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.AfterReceiveReply|mannioni|||||6|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/query/JCB_DEV/S_AND_OP?method=axis] end, duration = 18.510 s#

2016-02-26 08:42:39,685|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.DataServiceHelper.AxisQueryStreamEx|mannioni|||||6|Method [AxisQueryStreamEx] end, duration = 18.512 s#

2016-02-26 08:42:39,685|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.DataServiceHelper.ExecuteReportAsGzipStream|mannioni|||||6|Measure [Building report from json stream] start#

2016-02-26 08:42:39,688|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.JsonReportBuilder.BuildReportFromJsonStream|mannioni|||||6|Measure [Reading Axes and Cells from Json Stream] start#

2016-02-26 08:42:39,723|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.JsonReportBuilder.BuildReportFromJsonStream|mannioni|||||6|Measure [Reading Axes and Cells from Json Stream] end, duration = 0.032 s#

2016-02-26 08:42:39,735|TRACE|Performance|FPMXLClient.ReportManagement.ReportResult.BuildReportFromRealAxis|mannioni|||||6|Measure [Building Report from Axes and Cells] start#

2016-02-26 08:42:39,867|TRACE|Performance|FPMXLClient.ReportManagement.ReportResult.BuildReportFromRealAxis|mannioni|||||6|Measure [Building Report from Axes and Cells] end, duration = 0.130 s#

2016-02-26 08:42:39,868|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.DataServiceHelper.ExecuteReportAsGzipStream|mannioni|||||6|Measure [Building report from json stream] end, duration = 0.182 s#

2016-02-26 08:42:39,868|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.DataServiceHelper.ExecuteReportAsGzipStream|mannioni|||||6|Method [ExecuteReportAsStream] end, duration = 18.818 s#

2016-02-26 08:42:39,868|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ExecuteReport|mannioni|||||6|Method [ExecuteReport] end, duration = 18.822 s#

2016-02-26 08:42:39,868|TRACE|Performance|FPMXLClient.Office.EPMReportManager.ExecuteReportThreadStarter|mannioni|||||6|Method [ExecuteReportThreadStarter] end, duration = 18.824 s#

2016-02-26 08:42:39,884|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.ExecuteReport|mannioni|||||6|Measure [Execute Report000] end, duration = 18.849 s#

2016-02-26 08:42:39,908|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.RefreshReports|mannioni|||||1|Measure [Insert Local Members] start#

2016-02-26 08:42:40,023|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.RefreshReports|mannioni|||||1|Measure [Insert Local Members] end, duration = 0.114 s#

2016-02-26 08:42:40,453|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.RefreshReports|mannioni|||||1|Measure [Clear report] start#

2016-02-26 08:42:40,525|TRACE|Performance|FPMXLClient.Office.ExcelReportCleaner.ClearOnData|mannioni|||||1|Method [ClearOnData] start#

2016-02-26 08:42:40,870|TRACE|Performance|FPMXLClient.Office.ExcelReportCleaner.ClearOnData|mannioni|||||1|Method [ClearOnData] end, duration = 0.342 s#

2016-02-26 08:42:40,871|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.RefreshReports|mannioni|||||1|Measure [Clear report] end, duration = 0.417 s#

2016-02-26 08:42:40,877|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.WriteReport|mannioni|||||1|Method [Write Report] start#

2016-02-26 08:42:40,966|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property FST_DMD from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\afd04ea2c0c19d0d848cd373574291b0.data] start#

2016-02-26 08:42:40,971|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property FST_DMD from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\afd04ea2c0c19d0d848cd373574291b0.data] end, duration = 0.001 s#

2016-02-26 08:42:40,993|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property FST_DMD_U_FMT from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\d87cdb261840769084deb56d03f1814a.data] start#

2016-02-26 08:42:40,995|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property FST_DMD_U_FMT from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\d87cdb261840769084deb56d03f1814a.data] end, duration = 0.001 s#

2016-02-26 08:42:41,017|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteSmart|mannioni|||||1|Method [Write Smart] start#

2016-02-26 08:42:41,089|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.SmartLineSwitch|mannioni|||||1|Method [Switching Excel Rows] start#

2016-02-26 08:42:41,097|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.SmartLineSwitch|mannioni|||||1|Method [Switching Excel Rows] end, duration = 0.007 s#

2016-02-26 08:42:41,120|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.SmartColSwitch|mannioni|||||1|Method [Switching Columns] start#

2016-02-26 08:42:41,122|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.SmartColSwitch|mannioni|||||1|Method [Switching Columns] end, duration = 0.001 s#

2016-02-26 08:42:41,135|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteData|mannioni|||||1|Method [WriteData] start#

2016-02-26 08:42:42,985|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteData|mannioni|||||1|Method [WriteData] end, duration = 1.847 s#

2016-02-26 08:42:42,999|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Method [WriteRows] start#

2016-02-26 08:42:43,002|VERBOSE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Measure [Step1) GetRange] start#

2016-02-26 08:42:43,003|VERBOSE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Measure [Step1) GetRange] end, duration = 0.000 s#

2016-02-26 08:42:43,004|VERBOSE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Measure [Step2) ] start#

2016-02-26 08:42:43,005|VERBOSE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Measure [Step2) ] end, duration = 0.000 s#

2016-02-26 08:42:43,006|VERBOSE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Measure [Step3)] start#

2016-02-26 08:42:43,065|VERBOSE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Measure [Step3)] end, duration = 0.059 s#

2016-02-26 08:42:43,066|VERBOSE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Measure [Step4)] start#

2016-02-26 08:42:43,083|TRACE|Performance|FPMXLClient.Office.EPMExcelMultiRangeManager.SetSmartFormula|mannioni|||||1|Measure [SetSmartFormula] start#

2016-02-26 08:42:43,087|VERBOSE|Performance|FPMXLClient.Office.EPMExcelMultiRangeManager.RequireWritting|mannioni|||||1|Measure [RequireWritting] start#

2016-02-26 08:42:43,130|VERBOSE|Performance|FPMXLClient.Office.EPMExcelMultiRangeManager.RequireWritting|mannioni|||||1|Measure [RequireWritting] end, duration = 0.042 s#

2016-02-26 08:42:43,131|TRACE|Performance|FPMXLClient.Office.EPMExcelMultiRangeManager.SetSmartFormula|mannioni|||||1|Measure [SetSmartFormula] end, duration = 0.047 s#

2016-02-26 08:42:43,132|VERBOSE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Measure [Step4)] end, duration = 0.065 s#

2016-02-26 08:42:43,133|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Method [WriteRows] end, duration = 0.132 s#

2016-02-26 08:42:43,148|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteColumns|mannioni|||||1|Method [WriteColumns] start#

2016-02-26 08:42:43,157|TRACE|Performance|FPMXLClient.Office.EPMExcelMultiRangeManager.SetSmartFormula|mannioni|||||1|Measure [SetSmartFormula] start#

2016-02-26 08:42:43,159|VERBOSE|Performance|FPMXLClient.Office.EPMExcelMultiRangeManager.RequireWritting|mannioni|||||1|Measure [RequireWritting] start#

2016-02-26 08:42:43,160|VERBOSE|Performance|FPMXLClient.Office.EPMExcelMultiRangeManager.RequireWritting|mannioni|||||1|Measure [RequireWritting] end, duration = 0.000 s#

2016-02-26 08:42:43,161|TRACE|Performance|FPMXLClient.Office.EPMExcelMultiRangeManager.SetSmartFormula|mannioni|||||1|Measure [SetSmartFormula] end, duration = 0.003 s#

2016-02-26 08:42:43,162|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteColumns|mannioni|||||1|Method [WriteColumns] end, duration = 0.013 s#

2016-02-26 08:42:43,174|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteSmart|mannioni|||||1|Method [Write Smart] end, duration = 2.154 s#

2016-02-26 08:42:43,187|TRACE|Performance|FPMXLClient.ReportManagement.Axis.SetLocalMemberFormula|mannioni|||||1|Measure [SetLocalMemberFormula] start#

2016-02-26 08:42:43,404|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property PRDLINE from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\bc511f5608f96ffd0ea67f21700b41ae.data] start#

2016-02-26 08:42:43,410|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property PRDLINE from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\bc511f5608f96ffd0ea67f21700b41ae.data] end, duration = 0.005 s#

2016-02-26 08:42:43,530|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property S_SITE from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\566c7748f03f704e0404f9789c03ede1.data] start#

2016-02-26 08:42:43,533|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property S_SITE from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\566c7748f03f704e0404f9789c03ede1.data] end, duration = 0.002 s#

2016-02-26 08:42:43,594|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property PG from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\ccc1f98dc03a60c169402f11454c6c7e.data] start#

2016-02-26 08:42:43,605|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property PG from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\ccc1f98dc03a60c169402f11454c6c7e.data] end, duration = 0.010 s#

2016-02-26 08:42:43,666|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property SHORT_DESCRIPTION from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\04db1307cb329cac748a2b8d89c1b523.data] start#

2016-02-26 08:42:43,682|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property SHORT_DESCRIPTION from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\04db1307cb329cac748a2b8d89c1b523.data] end, duration = 0.015 s#

2016-02-26 08:42:44,191|TRACE|Performance|FPMXLClient.ReportManagement.Axis.SetLocalMemberFormula|mannioni|||||1|Measure [SetLocalMemberFormula] end, duration = 1.001 s#

2016-02-26 08:42:44,194|TRACE|Performance|FPMXLClient.Office.ExcelReportFormatter.FormattingReport|mannioni|||||1|Method [FormattingReport] start#

2016-02-26 08:42:44,208|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] start#

2016-02-26 08:42:44,236|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] end, duration = 0.027 s#

2016-02-26 08:42:44,462|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] start#

2016-02-26 08:42:44,465|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] end, duration = 0.003 s#

2016-02-26 08:42:44,590|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] start#

2016-02-26 08:42:44,594|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] end, duration = 0.003 s#

2016-02-26 08:42:45,084|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] start#

2016-02-26 08:42:45,088|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] end, duration = 0.003 s#

2016-02-26 08:42:47,342|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] start#

2016-02-26 08:42:47,349|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] end, duration = 0.006 s#

2016-02-26 08:42:51,929|TRACE|Performance|FPMXLClient.Office.ExcelReportFormatter.FormattingReport|mannioni|||||1|Method [FormattingReport] end, duration = 7.733 s#

2016-02-26 08:42:51,936|TRACE|Performance|FPMXLClient.Office.ExcelReportWriterHelper.CopyPasteInsertedZone|mannioni|||||1|Measure [InsertAfterRange 000] start#

2016-02-26 08:43:02,491|TRACE|Performance|FPMXLClient.Office.ExcelReportWriterHelper.CopyPasteInsertedZone|mannioni|||||1|Measure [InsertAfterRange 000] end, duration = 10.472 s#

2016-02-26 08:43:02,491|TRACE|Performance|FPMXLClient.Office.ExcelReportWriterHelper.CopyPasteInsertedZone|mannioni|||||1|Measure [InsertAfterRange 000] start#

2016-02-26 08:43:02,492|TRACE|Performance|FPMXLClient.Office.ExcelReportWriterHelper.CopyPasteInsertedZone|mannioni|||||1|Measure [InsertAfterRange 000] end, duration = 0.000 s#

2016-02-26 08:43:02,495|TRACE|Performance|FPMXLClient.ReportManagement.DynamicReport.Save|mannioni|||||1|Measure [Save report] start#

2016-02-26 08:43:02,568|TRACE|Performance|FPMXLClient.ReportManagement.DynamicReport.Save|mannioni|||||1|Measure [Save report] end, duration = 0.072 s#

2016-02-26 08:43:02,570|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.WriteReport|mannioni|||||1|Method [Write Report] end, duration = 21.691 s#

2016-02-26 08:43:02,573|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 08:43:02,574|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.000 s#

2016-02-26 08:43:02,575|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.RefreshReports|mannioni|||||1|Method [RefreshReports] end, duration = 41.560 s#

2016-02-26 08:43:02,587|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 08:43:02,588|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.000 s#

2016-02-26 08:43:02,590|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshReports|mannioni|||||1|Measure [Restore Calculation] start#

2016-02-26 08:43:02,597|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshReports|mannioni|||||1|Measure [Restore Calculation] end, duration = 0.006 s#

2016-02-26 08:43:02,598|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DeactivateXLLCommentsAndData|mannioni|||||1|Method [DeactivateXLLCommentsAndData] start#

2016-02-26 08:43:02,600|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DeactivateXLLCommentsAndData|mannioni|||||1|Method [DeactivateXLLCommentsAndData] end, duration = 0.002 s#

2016-02-26 08:43:02,602|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.ContainsCacheFunctions|mannioni|||||1|Method [Contains Cache Functions] start#

2016-02-26 08:43:02,607|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.ContainsCacheFunctions|mannioni|||||1|Method [Contains Cache Functions] end, duration = 0.004 s#

2016-02-26 08:43:02,609|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshCachesForAllConnections|mannioni|||||1|Measure [Refresh Caches] start#

2016-02-26 08:43:02,610|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshCachesForAllConnections|mannioni|||||1|Measure [Refresh Caches] end, duration = 0.000 s#

2016-02-26 08:43:02,611|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshSheet|mannioni|||||1|Measure [Flash Movie management] start#

2016-02-26 08:43:02,617|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshSheet|mannioni|||||1|Measure [Flash Movie management] end, duration = 0.005 s#

2016-02-26 08:43:02,617|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshSheet|mannioni|||||1|Method [RefreshSheet] end, duration = 45.188 s#

2016-02-26 08:43:02,618|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshSheetAndSetTime|mannioni|||||1|Measure [SetRefreshTime] start#

2016-02-26 08:43:02,695|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshSheetAndSetTime|mannioni|||||1|Measure [SetRefreshTime] end, duration = 0.077 s#

2016-02-26 08:43:02,696|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.ContainsEVFunctions|mannioni|||||1|Method [ContainsEVFunctions] start#

2016-02-26 08:43:02,699|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.ContainsEVFunctions|mannioni|||||1|Method [ContainsEVFunctions] end, duration = 0.003 s#

2016-02-26 08:43:02,699|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [AutoFitColumnsInAllReports] start#

2016-02-26 08:43:02,702|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [AutoFitColumnsInAllReports] end, duration = 0.002 s#

2016-02-26 08:43:02,703|TRACE|Performance|FPMXLClient.Office.VbaEventCaller.AfterRefresh|mannioni|||||1|Method [AfterRefresh VBA Caller] start#

2016-02-26 08:43:02,704|TRACE|Performance|FPMXLClient.Office.VbaEventCaller.AfterRefresh|mannioni|||||1|Method [AfterRefresh VBA Caller] end, duration = 0.001 s#

2016-02-26 08:43:02,704|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [RestoreExcelAutoFilter] start#

2016-02-26 08:43:02,707|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [RestoreExcelAutoFilter] end, duration = 0.002 s#

2016-02-26 08:43:02,707|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Measure [Block Screen update and Block Events unblock] start#

2016-02-26 08:43:02,708|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Measure [Block Screen update and Block Events unblock] end, duration = 0.000 s#

2016-02-26 08:43:02,708|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DeactivateXLLCommentsAndData|mannioni|||||1|Method [DeactivateXLLCommentsAndData] start#

2016-02-26 08:43:02,708|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DeactivateXLLCommentsAndData|mannioni|||||1|Method [DeactivateXLLCommentsAndData] end, duration = 0.000 s#

2016-02-26 08:43:02,709|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [DoRefreshDocument] end, duration = 45.486 s#

2016-02-26 08:43:02,710|TRACE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Method [Refresh Document] end, duration = 45.492 s#

2016-02-26 08:43:02,710|TRACE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Measure [Refresh sheet INPUT] end, duration = 45.714 s#

2016-02-26 08:43:02,710|VERBOSE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Measure [Refresh Sheet With Scan FINALLY] start#

2016-02-26 08:43:02,738|VERBOSE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Measure [Refresh Sheet With Scan FINALLY] end, duration = 0.027 s#

2016-02-26 08:43:02,739|DEBUG|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Method [RefreshSheetWithScan] end, duration = 45.834 s#

2016-02-26 08:43:06,916|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property FCSTPERIOD1 from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\ded2ed4df3bc8ea808c0bf0bf4b88130.data] start#

2016-02-26 08:43:06,918|TRACE|Performance|FPMXLClient.OlapUtilities.Property.ReadValuesFromCsvFile|mannioni|||||1|Measure [Time to load property FCSTPERIOD1 from cache file : C:\Users\ian.mannion\AppData\Local\EPMOfficeClient\Metadata\0cffb928f22215f82efb3f9c6d539331\353425dff199989ac3736f88dc6e9838\ded2ed4df3bc8ea808c0bf0bf4b88130.data] end, duration = 0.001 s#

2016-02-26 08:43:07,116|TRACE|Performance|FPMXLClient.Persistence.UserPreferenceHelperBase.Load|mannioni|||||1|Measure [Load User Preferences] start#

2016-02-26 08:43:07,118|TRACE|Performance|FPMXLClient.Persistence.UserPreferenceHelperBase.Load|mannioni|||||1|Measure [Load User Preferences] end, duration = 0.001 s#

2016-02-26 08:43:07,285|TRACE|Performance|FPMXLClient.Office.VbaEventCaller.AfterCurrentViewChange|mannioni|||||1|Method [AfterCurrentViewChange VBA Caller] end, duration = 51.030 s#

2016-02-26 08:43:14,939|DEBUG|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Method [RefreshSheetWithScan] start#

2016-02-26 08:43:14,939|TRACE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Measure [Refresh sheet Summary (PL)] start#

2016-02-26 08:43:14,940|VERBOSE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Measure [Warning in case of big report management] start#

2016-02-26 08:43:14,940|VERBOSE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Measure [Warning in case of big report management] end, duration = 0.000 s#

2016-02-26 08:43:14,940|TRACE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Measure [Undo management] start#

2016-02-26 08:43:14,940|TRACE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Measure [Undo management] end, duration = 0.000 s#

2016-02-26 08:43:14,941|TRACE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Method [Loading box management] start#

2016-02-26 08:43:14,975|TRACE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Method [Loading box management] end, duration = 0.034 s#

2016-02-26 08:43:14,976|TRACE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Method [Refresh Document] start#

2016-02-26 08:43:14,976|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [DoRefreshDocument] start#

2016-02-26 08:43:14,978|TRACE|Performance|FPMXLClient.Office.VbaEventCaller.BeforeRefresh|mannioni|||||1|Method [BeforeRefresh VBA Caller] start#

2016-02-26 08:43:14,979|TRACE|Performance|FPMXLClient.Office.VbaEventCaller.BeforeRefresh|mannioni|||||1|Method [BeforeRefresh VBA Caller] end, duration = 0.001 s#

2016-02-26 08:43:14,979|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.ClearAllCachesToGet|mannioni|||||1|Method [ClearAllCachesToGet] start#

2016-02-26 08:43:14,980|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.ClearAllCachesToGet|mannioni|||||1|Method [ClearAllCachesToGet] end, duration = 0.000 s#

2016-02-26 08:43:14,980|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [Check if the worksheet is an EpmSheet] start#

2016-02-26 08:43:14,980|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [Check if the worksheet is an EpmSheet] end, duration = 0.000 s#

2016-02-26 08:43:14,981|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DeactivateXLLCommentsAndData|mannioni|||||1|Method [DeactivateXLLCommentsAndData] start#

2016-02-26 08:43:14,981|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DeactivateXLLCommentsAndData|mannioni|||||1|Method [DeactivateXLLCommentsAndData] end, duration = 0.000 s#

2016-02-26 08:43:14,982|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.CheckIfSomeReportsContainsCellReferences|mannioni|||||1|Method [CheckIfSomeReportsContainsCellReferences] start#

2016-02-26 08:43:14,982|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 08:43:14,984|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.002 s#

2016-02-26 08:43:14,985|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.CheckIfSomeReportsContainsCellReferences|mannioni|||||1|Method [CheckIfSomeReportsContainsCellReferences] end, duration = 0.003 s#

2016-02-26 08:43:14,985|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.ContainsEVFunctions|mannioni|||||1|Method [ContainsEVFunctions] start#

2016-02-26 08:43:14,987|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.ContainsEVFunctions|mannioni|||||1|Method [ContainsEVFunctions] end, duration = 0.002 s#

2016-02-26 08:43:14,992|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.ContainsEPMFunctionsOverride|mannioni|||||1|Method [ContainsEPMFunctionsOverride] start#

2016-02-26 08:43:14,993|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.ContainsEPMFunctionsOverride|mannioni|||||1|Method [ContainsEPMFunctionsOverride] end, duration = 0.000 s#

2016-02-26 08:43:14,993|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [Calculate formulas involved in Report] start#

2016-02-26 08:43:14,993|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.CalculateCacheFunction|mannioni|||||1|Method [CalculateCacheFunction] start#

2016-02-26 08:43:14,994|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.ForceCalculationOnSheet|mannioni|||||1|Method [ForceCalculationOnSheet(Summary (PL))] start#

2016-02-26 08:43:14,996|TRACE|Performance|FPMXLClient.Office.CellBasedFunctionStatusNamedRange.AfterSheetCalculation|mannioni|||||1|Method [AfterSheetCalculation] start#

2016-02-26 08:43:14,998|TRACE|Performance|FPMXLClient.Office.CellBasedFunctionStatusNamedRange.AfterSheetCalculation|mannioni|||||1|Method [AfterSheetCalculation] end, duration = 0.002 s#

2016-02-26 08:43:15,002|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.ForceCalculationOnSheet|mannioni|||||1|Method [ForceCalculationOnSheet(Summary (PL))] end, duration = 0.008 s#

2016-02-26 08:43:15,002|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.CalculateCacheFunction|mannioni|||||1|Method [CalculateCacheFunction] end, duration = 0.008 s#

2016-02-26 08:43:15,003|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [Calculate formulas involved in Report] end, duration = 0.009 s#

2016-02-26 08:43:15,003|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshSheet|mannioni|||||1|Method [RefreshSheet] start#

2016-02-26 08:43:15,009|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshSheet|mannioni|||||1|Measure [Check HasAddedMembers] start#

2016-02-26 08:43:15,009|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshSheet|mannioni|||||1|Measure [Check HasAddedMembers] end, duration = 0.000 s#

2016-02-26 08:43:15,017|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 08:43:15,017|TRACE|Performance|FPMXLClient.ReportManagement.ScanningSession.InitNewSession|mannioni|||||1|Method [InitNewSession] start#

2016-02-26 08:43:15,084|TRACE|Performance|FPMXLClient.ReportManagement.ScanningSession.InitNewSession|mannioni|||||1|Method [InitNewSession] end, duration = 0.066 s#

2016-02-26 08:43:15,086|TRACE|Performance|FPMXLClient.ReportManagement.SheetReport.BuildFromScan|mannioni|||||1|Measure [Build from scan] start#

2016-02-26 08:43:15,087|TRACE|Performance|FPMXLClient.ReportManagement.SheetReport.ScanSheet|mannioni|||||1|Measure [Scanning sheet] start#

2016-02-26 08:43:15,636|TRACE|Performance|FPMXLClient.ReportManagement.SheetReport.ScanSheet|mannioni|||||1|Measure [Scanning sheet] end, duration = 0.549 s#

2016-02-26 08:43:15,637|TRACE|Performance|FPMXLClient.ReportManagement.SheetReport.BuildFromScan|mannioni|||||1|Measure [Build from scan] end, duration = 0.550 s#

2016-02-26 08:43:15,638|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.621 s#

2016-02-26 08:43:15,639|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 08:43:15,639|TRACE|Performance|FPMXLClient.ReportManagement.ScanningSession.InitNewSession|mannioni|||||1|Method [InitNewSession] start#

2016-02-26 08:43:15,696|TRACE|Performance|FPMXLClient.ReportManagement.ScanningSession.InitNewSession|mannioni|||||1|Method [InitNewSession] end, duration = 0.056 s#

2016-02-26 08:43:15,697|TRACE|Performance|FPMXLClient.ReportManagement.SheetReport.BuildFromScan|mannioni|||||1|Measure [Build from scan] start#

2016-02-26 08:43:15,698|TRACE|Performance|FPMXLClient.ReportManagement.SheetReport.ScanSheet|mannioni|||||1|Measure [Scanning sheet] start#

2016-02-26 08:43:16,228|TRACE|Performance|FPMXLClient.ReportManagement.SheetReport.ScanSheet|mannioni|||||1|Measure [Scanning sheet] end, duration = 0.530 s#

2016-02-26 08:43:16,229|TRACE|Performance|FPMXLClient.ReportManagement.SheetReport.BuildFromScan|mannioni|||||1|Measure [Build from scan] end, duration = 0.531 s#

2016-02-26 08:43:16,230|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.591 s#

2016-02-26 08:43:16,232|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 08:43:16,232|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.000 s#

2016-02-26 08:43:16,234|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.RefreshReports|mannioni|||||1|Method [RefreshReports] start#

2016-02-26 08:43:16,236|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.ExecuteParallelizedReportsFramework35|mannioni|||||1|Measure [Parallelized Execution] start#

2016-02-26 08:43:16,237|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.ExecuteParallelizedReportsFramework35|mannioni|||||1|Measure [Parallelized Execution] end, duration = 0.001 s#

2016-02-26 08:43:16,237|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.ExecuteReport|mannioni|||||15|Measure [Execute Report000] start#

2016-02-26 08:43:16,238|TRACE|Performance|FPMXLClient.Office.EPMReportManager.ExecuteReportThreadStarter|mannioni|||||15|Method [ExecuteReportThreadStarter] start#

2016-02-26 08:43:16,238|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ExecuteReport|mannioni|||||15|Method [ExecuteReport] start#

2016-02-26 08:43:16,239|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.DataServiceHelper.ExecuteReportAsGzipStream|mannioni|||||15|Method [ExecuteReportAsStream] start#

2016-02-26 08:43:16,239|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.DataServiceHelper.ExecuteReportAsGzipStream|mannioni|||||15|Measure [Building Axis Request] start#

2016-02-26 08:43:16,239|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.JsonQueryAxisBuilder.BuildAxisQuery|mannioni|||||15|Method [Build AxisQuery As Stream] start#

2016-02-26 08:43:16,240|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.JsonQueryAxisBuilder.WriteSymmetricAxis|mannioni|||||15|Method [Build Symmetric Axis as Stream] start#

2016-02-26 08:43:16,240|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.JsonQueryAxisBuilder.WriteSymmetricAxis|mannioni|||||15|Method [Build Symmetric Axis as Stream] end, duration = 0.000 s#

2016-02-26 08:43:16,291|TRACE|Performance|FPMXLClient.ReportManagement.ExpandedAxis.ApplyDynamicFiltering|mannioni|||||15|Measure [Apply filtering new method] start#

2016-02-26 08:43:16,294|TRACE|Performance|FPMXLClient.ReportManagement.ExpandedAxis.ApplyDynamicFiltering|mannioni|||||15|Measure [Apply filtering new method] end, duration = 0.002 s#

2016-02-26 08:43:16,297|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.JsonQueryAxisBuilder.BuildAxisQuery|mannioni|||||15|Method [Build AxisQuery As Stream] end, duration = 0.057 s#

2016-02-26 08:43:16,297|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.DataServiceHelper.ExecuteReportAsGzipStream|mannioni|||||15|Measure [Building Axis Request] end, duration = 0.058 s#

2016-02-26 08:43:16,297|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.DataServiceHelper.AxisQueryStreamEx|mannioni|||||15|Method [AxisQueryStreamEx] start, parameters:

    name = JCB_DEV

    parameters = S_AND_OP#

2016-02-26 08:43:16,297|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.BeforeSendRequest|mannioni|||||15|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/query/JCB_DEV/S_AND_OP?method=axis] start#

2016-02-26 09:10:06,637|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.AfterReceiveReply|mannioni|||||15|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/query/JCB_DEV/S_AND_OP?method=axis] end, duration = 1610.339 s#

2016-02-26 09:10:06,638|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.DataServiceHelper.AxisQueryStreamEx|mannioni|||||15|Method [AxisQueryStreamEx] end, duration = 1610.341 s#

2016-02-26 09:10:06,639|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.DataServiceHelper.ExecuteReportAsGzipStream|mannioni|||||15|Measure [Building report from json stream] start#

2016-02-26 09:10:06,640|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.JsonReportBuilder.BuildReportFromJsonStream|mannioni|||||15|Measure [Reading Axes and Cells from Json Stream] start#

2016-02-26 09:10:06,662|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.JsonReportBuilder.BuildReportFromJsonStream|mannioni|||||15|Measure [Reading Axes and Cells from Json Stream] end, duration = 0.021 s#

2016-02-26 09:10:06,663|TRACE|Performance|FPMXLClient.ReportManagement.ReportResult.BuildReportFromRealAxis|mannioni|||||15|Measure [Building Report from Axes and Cells] start#

2016-02-26 09:10:06,720|TRACE|Performance|FPMXLClient.ReportManagement.ReportResult.BuildReportFromRealAxis|mannioni|||||15|Measure [Building Report from Axes and Cells] end, duration = 0.056 s#

2016-02-26 09:10:06,720|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.DataServiceHelper.ExecuteReportAsGzipStream|mannioni|||||15|Measure [Building report from json stream] end, duration = 0.080 s#

2016-02-26 09:10:06,720|TRACE|Performance|FPMXLClient.BPCProxy.MetaData_Data.DataServiceHelper.ExecuteReportAsGzipStream|mannioni|||||15|Method [ExecuteReportAsStream] end, duration = 1610.481 s#

2016-02-26 09:10:06,720|TRACE|Performance|FPMXLClient.Connection.RESTConnection.ExecuteReport|mannioni|||||15|Method [ExecuteReport] end, duration = 1610.481 s#

2016-02-26 09:10:06,721|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.ExecuteReport|mannioni|||||15|Measure [Execute Report000] end, duration = 1610.483 s#

2016-02-26 09:10:06,721|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.RefreshReports|mannioni|||||1|Measure [Insert Local Members] start#

2016-02-26 09:10:06,721|TRACE|Performance|FPMXLClient.Office.EPMReportManager.ExecuteReportThreadStarter|mannioni|||||15|Method [ExecuteReportThreadStarter] end, duration = 1610.483 s#

2016-02-26 09:10:06,733|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.RefreshReports|mannioni|||||1|Measure [Insert Local Members] end, duration = 0.011 s#

2016-02-26 09:10:06,743|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.RefreshReports|mannioni|||||1|Measure [Clear report] start#

2016-02-26 09:10:06,750|TRACE|Performance|FPMXLClient.Office.ExcelReportCleaner.ClearOnData|mannioni|||||1|Method [ClearOnData] start#

2016-02-26 09:10:06,766|TRACE|Performance|FPMXLClient.Office.ExcelReportCleaner.ClearOnData|mannioni|||||1|Method [ClearOnData] end, duration = 0.015 s#

2016-02-26 09:10:06,766|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.RefreshReports|mannioni|||||1|Measure [Clear report] end, duration = 0.023 s#

2016-02-26 09:10:06,768|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.WriteReport|mannioni|||||1|Method [Write Report] start#

2016-02-26 09:10:06,770|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteSmart|mannioni|||||1|Method [Write Smart] start#

2016-02-26 09:10:06,788|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.SmartLineSwitch|mannioni|||||1|Method [Switching Excel Rows] start#

2016-02-26 09:10:06,789|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.SmartLineSwitch|mannioni|||||1|Method [Switching Excel Rows] end, duration = 0.001 s#

2016-02-26 09:10:06,789|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.SmartColSwitch|mannioni|||||1|Method [Switching Columns] start#

2016-02-26 09:10:06,790|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.SmartColSwitch|mannioni|||||1|Method [Switching Columns] end, duration = 0.000 s#

2016-02-26 09:10:06,791|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteData|mannioni|||||1|Method [WriteData] start#

2016-02-26 09:10:10,202|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteData|mannioni|||||1|Method [WriteData] end, duration = 3.411 s#

2016-02-26 09:10:10,204|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Method [WriteRows] start#

2016-02-26 09:10:10,205|VERBOSE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Measure [Step1) GetRange] start#

2016-02-26 09:10:10,205|VERBOSE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Measure [Step1) GetRange] end, duration = 0.000 s#

2016-02-26 09:10:10,206|VERBOSE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Measure [Step2) ] start#

2016-02-26 09:10:10,207|VERBOSE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Measure [Step2) ] end, duration = 0.000 s#

2016-02-26 09:10:10,207|VERBOSE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Measure [Step3)] start#

2016-02-26 09:10:10,209|VERBOSE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Measure [Step3)] end, duration = 0.002 s#

2016-02-26 09:10:10,209|VERBOSE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Measure [Step4)] start#

2016-02-26 09:10:10,224|TRACE|Performance|FPMXLClient.Office.EPMExcelMultiRangeManager.SetSmartFormula|mannioni|||||1|Measure [SetSmartFormula] start#

2016-02-26 09:10:10,225|VERBOSE|Performance|FPMXLClient.Office.EPMExcelMultiRangeManager.RequireWritting|mannioni|||||1|Measure [RequireWritting] start#

2016-02-26 09:10:10,228|VERBOSE|Performance|FPMXLClient.Office.EPMExcelMultiRangeManager.RequireWritting|mannioni|||||1|Measure [RequireWritting] end, duration = 0.003 s#

2016-02-26 09:10:10,228|TRACE|Performance|FPMXLClient.Office.EPMExcelMultiRangeManager.SetSmartFormula|mannioni|||||1|Measure [SetSmartFormula] end, duration = 0.004 s#

2016-02-26 09:10:10,229|VERBOSE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Measure [Step4)] end, duration = 0.019 s#

2016-02-26 09:10:10,229|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteRows|mannioni|||||1|Method [WriteRows] end, duration = 0.025 s#

2016-02-26 09:10:10,229|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteColumns|mannioni|||||1|Method [WriteColumns] start#

2016-02-26 09:10:10,231|TRACE|Performance|FPMXLClient.Office.EPMExcelMultiRangeManager.SetSmartFormula|mannioni|||||1|Measure [SetSmartFormula] start#

2016-02-26 09:10:10,231|VERBOSE|Performance|FPMXLClient.Office.EPMExcelMultiRangeManager.RequireWritting|mannioni|||||1|Measure [RequireWritting] start#

2016-02-26 09:10:10,232|VERBOSE|Performance|FPMXLClient.Office.EPMExcelMultiRangeManager.RequireWritting|mannioni|||||1|Measure [RequireWritting] end, duration = 0.000 s#

2016-02-26 09:10:10,232|TRACE|Performance|FPMXLClient.Office.EPMExcelMultiRangeManager.SetSmartFormula|mannioni|||||1|Measure [SetSmartFormula] end, duration = 0.001 s#

2016-02-26 09:10:10,233|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteColumns|mannioni|||||1|Method [WriteColumns] end, duration = 0.003 s#

2016-02-26 09:10:10,234|TRACE|Performance|FPMXLClient.Office.ExcelReportWriter.WriteSmart|mannioni|||||1|Method [Write Smart] end, duration = 3.464 s#

2016-02-26 09:10:10,235|TRACE|Performance|FPMXLClient.ReportManagement.Axis.SetLocalMemberFormula|mannioni|||||1|Measure [SetLocalMemberFormula] start#

2016-02-26 09:10:10,423|TRACE|Performance|FPMXLClient.ReportManagement.Axis.SetLocalMemberFormula|mannioni|||||1|Measure [SetLocalMemberFormula] end, duration = 0.188 s#

2016-02-26 09:10:10,424|TRACE|Performance|FPMXLClient.ReportManagement.Axis.SetLocalMemberFormula|mannioni|||||1|Measure [SetLocalMemberFormula] start#

2016-02-26 09:10:10,706|TRACE|Performance|FPMXLClient.ReportManagement.Axis.SetLocalMemberFormula|mannioni|||||1|Measure [SetLocalMemberFormula] end, duration = 0.282 s#

2016-02-26 09:10:10,708|TRACE|Performance|FPMXLClient.Office.ExcelReportFormatter.FormattingReport|mannioni|||||1|Method [FormattingReport] start#

2016-02-26 09:10:10,708|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] start#

2016-02-26 09:10:10,711|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] end, duration = 0.002 s#

2016-02-26 09:10:10,761|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] start#

2016-02-26 09:10:10,764|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] end, duration = 0.002 s#

2016-02-26 09:10:10,783|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] start#

2016-02-26 09:10:10,787|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] end, duration = 0.003 s#

2016-02-26 09:10:10,808|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] start#

2016-02-26 09:10:10,811|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] end, duration = 0.002 s#

2016-02-26 09:10:10,880|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] start#

2016-02-26 09:10:10,884|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] end, duration = 0.003 s#

2016-02-26 09:10:10,896|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] start#

2016-02-26 09:10:10,900|TRACE|Performance|FPMXLClient.Office.EPMExcelHelper.GetListRangeDataByRowCorrespondingToList|mannioni|||||1|Measure [GetListRangeDataByRowCorrespondingToList] end, duration = 0.003 s#

2016-02-26 09:10:11,089|TRACE|Performance|FPMXLClient.Office.ExcelReportFormatter.FormattingReport|mannioni|||||1|Method [FormattingReport] end, duration = 0.380 s#

2016-02-26 09:10:11,089|TRACE|Performance|FPMXLClient.Office.ExcelReportWriterHelper.CopyPasteInsertedZone|mannioni|||||1|Measure [InsertAfterRange 000] start#

2016-02-26 09:10:11,090|TRACE|Performance|FPMXLClient.Office.ExcelReportWriterHelper.CopyPasteInsertedZone|mannioni|||||1|Measure [InsertAfterRange 000] end, duration = 0.000 s#

2016-02-26 09:10:11,091|TRACE|Performance|FPMXLClient.Office.ExcelReportWriterHelper.CopyPasteInsertedZone|mannioni|||||1|Measure [InsertAfterRange 000] start#

2016-02-26 09:10:11,091|TRACE|Performance|FPMXLClient.Office.ExcelReportWriterHelper.CopyPasteInsertedZone|mannioni|||||1|Measure [InsertAfterRange 000] end, duration = 0.000 s#

2016-02-26 09:10:11,091|TRACE|Performance|FPMXLClient.Office.ExcelReportWriterHelper.copyPasteAutoFill|mannioni|||||1|Method [CopyPasteAutoFill] start#

2016-02-26 09:10:16,487|TRACE|Performance|FPMXLClient.Office.ExcelReportWriterHelper.copyPasteAutoFill|mannioni|||||1|Method [CopyPasteAutoFill] end, duration = 5.395 s#

2016-02-26 09:10:16,487|TRACE|Performance|FPMXLClient.ReportManagement.DynamicReport.Save|mannioni|||||1|Measure [Save report] start#

2016-02-26 09:10:16,500|TRACE|Performance|FPMXLClient.ReportManagement.DynamicReport.Save|mannioni|||||1|Measure [Save report] end, duration = 0.012 s#

2016-02-26 09:10:16,500|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.WriteReport|mannioni|||||1|Method [Write Report] end, duration = 9.732 s#

2016-02-26 09:10:16,504|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 09:10:16,504|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.000 s#

2016-02-26 09:10:16,505|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.RefreshReports|mannioni|||||1|Method [RefreshReports] end, duration = 1620.271 s#

2016-02-26 09:10:16,506|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 09:10:16,507|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.000 s#

2016-02-26 09:10:16,756|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 09:10:16,756|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.000 s#

2016-02-26 09:10:16,757|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshReports|mannioni|||||1|Measure [Restore Calculation] start#

2016-02-26 09:10:16,769|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshReports|mannioni|||||1|Measure [Restore Calculation] end, duration = 0.012 s#

2016-02-26 09:10:16,769|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DeactivateXLLCommentsAndData|mannioni|||||1|Method [DeactivateXLLCommentsAndData] start#

2016-02-26 09:10:16,770|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DeactivateXLLCommentsAndData|mannioni|||||1|Method [DeactivateXLLCommentsAndData] end, duration = 0.000 s#

2016-02-26 09:10:16,770|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.ContainsCacheFunctions|mannioni|||||1|Method [Contains Cache Functions] start#

2016-02-26 09:10:16,771|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.ContainsCacheFunctions|mannioni|||||1|Method [Contains Cache Functions] end, duration = 0.001 s#

2016-02-26 09:10:16,772|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshCachesForAllConnections|mannioni|||||1|Measure [Refresh Caches] start#

2016-02-26 09:10:16,772|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshCachesForAllConnections|mannioni|||||1|Measure [Refresh Caches] end, duration = 0.000 s#

2016-02-26 09:10:16,772|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshSheet|mannioni|||||1|Measure [Flash Movie management] start#

2016-02-26 09:10:16,773|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshSheet|mannioni|||||1|Measure [Flash Movie management] end, duration = 0.001 s#

2016-02-26 09:10:16,774|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshSheet|mannioni|||||1|Method [RefreshSheet] end, duration = 1621.772 s#

2016-02-26 09:10:16,774|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshSheetAndSetTime|mannioni|||||1|Measure [SetRefreshTime] start#

2016-02-26 09:10:16,834|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.RefreshSheetAndSetTime|mannioni|||||1|Measure [SetRefreshTime] end, duration = 0.060 s#

2016-02-26 09:10:16,834|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.ContainsEVFunctions|mannioni|||||1|Method [ContainsEVFunctions] start#

2016-02-26 09:10:16,836|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.ContainsEVFunctions|mannioni|||||1|Method [ContainsEVFunctions] end, duration = 0.001 s#

2016-02-26 09:10:16,836|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [AutoFitColumnsInAllReports] start#

2016-02-26 09:10:16,836|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [AutoFitColumnsInAllReports] end, duration = 0.000 s#

2016-02-26 09:10:16,837|TRACE|Performance|FPMXLClient.Office.VbaEventCaller.AfterRefresh|mannioni|||||1|Method [AfterRefresh VBA Caller] start#

2016-02-26 09:10:16,837|TRACE|Performance|FPMXLClient.Office.VbaEventCaller.AfterRefresh|mannioni|||||1|Method [AfterRefresh VBA Caller] end, duration = 0.000 s#

2016-02-26 09:10:16,838|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [RestoreExcelAutoFilter] start#

2016-02-26 09:10:16,838|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [RestoreExcelAutoFilter] end, duration = 0.000 s#

2016-02-26 09:10:16,838|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Measure [Block Screen update and Block Events unblock] start#

2016-02-26 09:10:16,838|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Measure [Block Screen update and Block Events unblock] end, duration = 0.000 s#

2016-02-26 09:10:16,839|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DeactivateXLLCommentsAndData|mannioni|||||1|Method [DeactivateXLLCommentsAndData] start#

2016-02-26 09:10:16,839|VERBOSE|Performance|FPMXLClient.Office.EPMExcelSheet.DeactivateXLLCommentsAndData|mannioni|||||1|Method [DeactivateXLLCommentsAndData] end, duration = 0.000 s#

2016-02-26 09:10:16,839|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.DoRefreshDocument|mannioni|||||1|Method [DoRefreshDocument] end, duration = 1621.864 s#

2016-02-26 09:10:16,839|TRACE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Method [Refresh Document] end, duration = 1621.864 s#

2016-02-26 09:10:16,840|TRACE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Measure [Refresh sheet Summary (PL)] end, duration = 1621.901 s#

2016-02-26 09:10:16,840|VERBOSE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Measure [Refresh Sheet With Scan FINALLY] start#

2016-02-26 09:10:16,851|VERBOSE|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Measure [Refresh Sheet With Scan FINALLY] end, duration = 0.011 s#

2016-02-26 09:10:16,851|DEBUG|Performance|FPMXLClient.AddIn.RefreshSheetWithScan|mannioni|||||1|Method [RefreshSheetWithScan] end, duration = 1621.913 s#

2016-02-26 09:10:17,813|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 09:10:17,814|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.000 s#

2016-02-26 09:10:17,820|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 09:10:17,820|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.000 s#

2016-02-26 09:58:41,756|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 09:58:41,757|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.000 s#

2016-02-26 09:58:41,759|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 09:58:41,759|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.000 s#

2016-02-26 09:58:41,820|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.GetAllDataToSubmit|mannioni|||||1|Method [Gather all data to submit on INPUT] start#

2016-02-26 09:58:41,824|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.OperationBeforeSubmit|mannioni|||||1|Method [OperationBeforeSubmit] start#

2016-02-26 09:58:41,834|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.OperationBeforeSubmit|mannioni|||||1|Method [OperationBeforeSubmit] end, duration = 0.009 s#

2016-02-26 09:58:41,844|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] start#

2016-02-26 09:58:41,845|TRACE|Performance|FPMXLClient.Office.EPMExcelReportManager.LoadReports|mannioni|||||1|Method [Load Reports] end, duration = 0.000 s#

2016-02-26 09:58:42,023|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.GetDataToSubmitFromEVFunctions|mannioni|||||1|Measure [] start#

2016-02-26 09:58:42,024|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.GetDataToSubmitFromEVFunctions|mannioni|||||1|Measure [] end, duration = 0.000 s#

2016-02-26 09:58:42,024|TRACE|Performance|FPMXLClient.Office.EPMExcelSheet.GetAllDataToSubmit|mannioni|||||1|Method [Gather all data to submit on INPUT] end, duration = 0.203 s#

2016-02-26 09:58:51,395|VERBOSE|Performance|FPMXLClient.AddIn.ClearEVPROCache|mannioni|||||1|Method [ClearEVPROXllCache] start#

2016-02-26 09:58:51,396|VERBOSE|Performance|FPMXLClient.AddIn.ClearEVPROCache|mannioni|||||1|Method [ClearEVPROXllCache] end, duration = 0.000 s#

2016-02-26 09:58:51,398|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] start#

2016-02-26 09:58:51,398|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] end, duration = 0.000 s#

2016-02-26 09:58:51,398|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] start#

2016-02-26 09:58:51,399|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] end, duration = 0.000 s#

2016-02-26 09:58:51,412|TRACE|Performance|FPMXLClient.BPCProxy.Session.SessionServiceHelper.Logoff|mannioni|||||1|Method [Logoff] start#

2016-02-26 09:58:51,412|DEBUG|Performance|FPMXLClient.BPCProxy.Common.Wcf.TraceOperationInspector.BeforeSendRequest|mannioni|||||1|Remote call [http://sapbwp.jcb.local:8802/sap/bpc/session?method=Logout] start#

2016-02-26 09:58:52,588|TRACE|Performance|FPMXLClient.BPCProxy.Session.SessionServiceHelper.Logoff|mannioni|||||1|Method [Logoff] end, duration = 1.176 s#

2016-02-26 09:58:52,596|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] start#

2016-02-26 09:58:52,597|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] end, duration = 0.000 s#

2016-02-26 09:58:52,598|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] start#

2016-02-26 09:58:52,599|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] end, duration = 0.000 s#

2016-02-26 09:58:52,599|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] start#

2016-02-26 09:58:52,600|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] end, duration = 0.000 s#

2016-02-26 09:58:52,600|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] start#

2016-02-26 09:58:52,601|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] end, duration = 0.000 s#

2016-02-26 09:58:52,601|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] start#

2016-02-26 09:58:52,602|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] end, duration = 0.000 s#

2016-02-26 09:58:52,602|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] start#

2016-02-26 09:58:52,602|VERBOSE|Performance|FPMXLClient.TechnicalCategory.ClearXllCache|mannioni|||||1|Method [ClearXllCache] end, duration = 0.000 s#

Show replies
Show replies
Former Member
‎2016 Feb 25 3:16 PM
0 Likes

Hi

We have found that by opening the EPM and deleting the local Meta Data then allows the report to run as expected.  I assume this was to do with the new members on the server but not on the local copy of the cache?

Is there anything we can do to automate this local cache updating other than asking everyone to delete ?

Thanks

Ian

Show replies
Show replies
Former Member
‎2016 Feb 25 3:51 PM
0 Likes

Hi Ian,

Are you using "Add members on the fly" tool?

-Gersh

Former Member
‎2016 Feb 26 9:45 AM
0 Likes

Hi Ian,

yes, you can automate this step, but only by using VBA. There is a method for deleting the metadata cache(ClearMetadataCache) in the class EPMAddInAutomation.

Best regards,

Karsten

former_member186338
Active Contributor
‎2016 Feb 26 9:49 AM
0 Likes

Hi Karsten,

I think Ian already evaluated some VBA solution based on: "Is there a way to enforce this setting on all our users other than configuring a windows script ?"

He is looking for some BPC server based solution, but I think it's not possible...

Vadim

Former Member
‎2016 Feb 26 10:04 AM
0 Likes

Hi Vadim,

your are right, there is no server based solution for fixing this issue. Thus, I mentioned the VBA approach. I appologize if Ian already considered this as not an option.

In fact, there are only two ways to bypass this issue since the metadaat are stored on the client machines: VBA or, the worst way, a batch file for delting the folders created by system.

Karsten

Former Member
‎2016 Feb 24 4:51 PM
0 Likes

Hi Ian,

Can you see how many records that call brought? What DB are you using?

Regards,

Gersh

Show replies
Show replies
Former Member
‎2016 Feb 24 12:10 PM
0 Likes

So many things u've tried so far good,..if ur using excel-2007/10 pl try once in excel-2013 with sp24.

Show replies
Show replies
former_member186338
Active Contributor
‎2016 Feb 24 12:30 PM
0 Likes

Hi Ravi,

Please read

4. ...avoid slang and SMS Text Speak.


Vadim

Former Member
‎2016 Feb 25 5:07 AM
0 Likes

Hi Mr.vadim,

Sorry for Inconvenience caused !!!

Sure I will come up with full text.

Thanks for correcting me.

former_member186338
Active Contributor
‎2016 Feb 24 11:28 AM
0 Likes

Hi Ian,

Do you have dimension member formulas and non standard measures in your report?

Vadim

P.S. What is your core BPC and BW versions/SP?

Show replies
Show replies
Former Member
‎2016 Feb 24 4:21 PM
0 Likes

Hi Vadim

Thanks for the reply and apologies for the lack of initial information:

SAP BW 730 SP14

CPMBPC 800 SP17

EPM10 SP21

We are trying to run a plan report with no member formulas etc..

One thing to add, we have found that by selecting the EPM option of 'Clear Metadata Cache' then allows all of our reports to run in expected times.

We now need to work out why this would be needed.

One thing we do know, there have be around 250 dimension members added recently.  Is there a way to enforce this setting on all our users other than configuring a windows script ?

Thanks

Ian

Ask a Question