locked
WACK "Performance Launch" FAILED on every app, even blank.

    Question

  • Hello everyone. I've been trying to put a very simple app on store, but seems like this WACK won't let me. I have everything up, the only problem is, WACK keep giving me this every single time;

                

    FAILED                      Performance launch
    • Error Found: The performance launch test collected the following results:.
      • Application Error: Application Launch was not detected for application App. This could be because your application failed to launch correctly. Please consider re-running the test and avoid interacting with the application while tests are running.
    • Impact if not fixed: Application launch time is important for creating a fast and fluid experience for the user. This app will not be accepted by the Windows Store.
    • How to fix: You should ensure that your app’s performance is consistent across different machine configurations and does not exceed the minimum requirements or it will potentially fail during Windows store onboarding. The informational metrics can provide insight into areas that could help improve your app’s performance, but do not impact your apps acceptance by the Windows Store. See link below for more information: Performance Best Practices

    Even on a blank app, I keep getting this error. Hope someone can help me about this. Thank you.

     
    Wednesday, March 13, 2013 6:02 PM

Answers

  • Hello OnurYIL,

    Are you by any chance running on a Turkish version of Windows? If yes, could you please try, as a test, to change your display language to English and run the WACK test again? (Add English in the Languages configuration and set it to be the top-most language. Then log off and on again and run WACK.)

    Please let me know whether this enabled you to pass the test on your machine.

    FYI: If this issue has been stopping you from submitting your app to the store, please just go ahead and submit it. From my past experience, this issue only happens on specific machines with specific language settings. The apps from affected customer did not fail the performance launch test during the certification.

    Cheers


    Helge Mahrt - Microsoft Developer Support Engineer - If my reply answers your question, please mark this post as answered.

    • Marked as answer by OnurYIL Sunday, April 21, 2013 4:21 PM
    Tuesday, March 19, 2013 9:05 AM

All replies

  • Hi OnurYIL,

    What's the version of your WACK? Try re-installing the lastest version if you haven't done so. http://msdn.microsoft.com/en-us/windows/desktop/hh852363.aspx

    Also, try running the standalone Windows App Cert Kit and pick an installed Windows Store app and see if the issue presists.

    Best regards,


    Min Zhu
    MSDN Community Support | Feedback to us
    Develop and promote your apps in Windows Store
    Please remember to mark the replies as answers if they help and unmark them if they provide no help.

    Thursday, March 14, 2013 2:49 AM
    Moderator
  • Hi Min Zhu,

    Thank you for your reply. I already installed the latest version of WACK. I'm working on this issue for 3 days now and I think there's a problem with my developer account. I can pass the WACK when I create packcage and test WACK WITOUT signing in. But once I sign in with my account and pick a reserved name and create package, I can't pass the WACK, I get the performance error. When I sign in and create package, my app's;

    Package name:

    Publisher:

    Publisher display name:

    Package family name:

    Automatically changes to something else, and I can't edit any of these ever again. When I try to edit, lets say Package name, it gives an error which says "I have to use "xyz" package name for this app" or something like that. So I think there's something wrong with this. 
    Also same thing happens when I try to submit my package on website. I create package w/o signing in. Then go to site and upload packages, and I get an error which tells me I have to change the package name to xxx. 

    Edit: Here are some more info I got. I tracked the performance launch tools and found test resultsin  " %userprofile%\AppData\Local\Microsoft\AppCertKit\ " path.  I found the "Performance_Test__trace_Launch_3142013_xxxxxx" files which I think contains info about the performance test. Here are the 2 results I got. First one is the app that packkaged WITHOUT sign in, and other one is the one packaged after signed in. I will underline the parths I found might cause the error.

    Packaged without signed in :

    program Information: 0 : 14.3.2013 20:31:22 INFO:[Configuration:LoadSettings]overridepath - %localappdata%\Microsoft\AppCertKitPerfConfig
    program Information: 0 : 14.3.2013 20:31:22 INFO:[Configuration:LoadSettings]disablexperf - false
    program Information: 0 : 14.3.2013 20:31:22 INFO:[Configuration:LoadSettings]xperfpath - ..\Windows Performance Toolkit\xperf.exe
    program Information: 0 : 14.3.2013 20:31:22 INFO:[Configuration:LoadSettings]xperfflags - Base+FILE_IO+FILE_IO_INIT+CSWITCH+MEMORY
    program Information: 0 : 14.3.2013 20:31:22 INFO:[Configuration:LoadSettings]xperfuserflags - Microsoft-Windows-Immersive-Shell+Win32HeapRanges
    program Information: 0 : 14.3.2013 20:31:22 INFO:[Configuration:LoadSettings]xperfminbuffers - 10
    program Information: 0 : 14.3.2013 20:31:22 INFO:[Configuration:LoadSettings]xperftimeoutmsec - 300000
    program Information: 0 : 14.3.2013 20:31:22 INFO:[Configuration:LoadSettings]systemcfgtimeoutmsec - 10000
    program Information: 0 : 14.3.2013 20:31:22 INFO:[Configuration:LoadSettings]runngen - true
    program Information: 0 : 14.3.2013 20:31:22 INFO:[Configuration:LoadSettings]launchtesttimemsec - 10000
    program Information: 0 : 14.3.2013 20:31:22 INFO:[Configuration:LoadSettings]suspendtesttimemsec - 15000
    program Information: 0 : 14.3.2013 20:31:22 INFO:[Configuration:LoadSettings]launchwarningsec - 3
    program Information: 0 : 14.3.2013 20:31:22 INFO:[Configuration:LoadSettings]launchfailsec - 5
    program Information: 0 : 14.3.2013 20:31:22 INFO:[Configuration:LoadSettings]maxcpuusagepercent - 50
    program Information: 0 : 14.3.2013 20:31:22 INFO:[Configuration:LoadSettings]maxlaunchfileiomb - 50
    program Information: 0 : 14.3.2013 20:31:22 INFO:[Configuration:LoadSettings]maxsuspendfileiomb - 20
    program Information: 0 : 14.3.2013 20:31:22 INFO:[Configuration:LoadSettings]maxprivatepagesmb - 25
    program Information: 0 : 14.3.2013 20:31:22 INFO:[Configuration:LoadSettings]suspendfailsec - 2
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ConfigFile::GetConfig]Override path = C:\Users\Onur\AppData\Local\Microsoft\AppCertKitPerfConfig\Microsoft.Windows.SoftwareLogo.Performance.dll.config
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ConfigFile::ReadStringEntry]Reading entry: xperfpath
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ConfigFile::ReadStringEntry]Reading entry: disablexperf
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ConfigFile::ReadStringEntry]Reading entry: xperfflags
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ConfigFile::ReadStringEntry]Reading entry: xperfuserflags
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ConfigFile::ReadStringEntry]Reading entry: xperfminbuffers
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ConfigFile::ReadStringEntry]Reading entry: xperftimeoutmsec
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ConfigFile::ReadStringEntry]Reading entry: systemcfgtimeoutmsec
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ConfigFile::ReadStringEntry]Reading entry: runNgen
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ConfigFile::ReadStringEntry]Reading entry: launchtesttimemsec
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ConfigFile::ReadStringEntry]Reading entry: suspendtesttimemsec
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ConfigFile::ReadStringEntry]Reading entry: launchwarningsec
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ConfigFile::ReadStringEntry]Reading entry: launchfailsec
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ConfigFile::ReadStringEntry]Reading entry: maxcpuusagepercent
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ConfigFile::ReadStringEntry]Reading entry: maxlaunchfileioMB
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ConfigFile::ReadStringEntry]Reading entry: maxsuspendfileioMB
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ConfigFile::ReadStringEntry]Reading entry: maxprivatepagesMB
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ConfigFile::ReadStringEntry]Reading entry: suspendfailsec
    program Information: 0 : 14.3.2013 20:31:22 INFO:[AppXPackage::GetPackageFromManifestFile]Loading Manifest.
    program Information: 0 : 14.3.2013 20:31:22 INFO:[AppXPackage::GetPackageFromManifestFile]Finding the Package node.
    program Information: 0 : 14.3.2013 20:31:22 INFO:[AppXPackage::GetPackageFromManifestFile]Creating namespace and identity node.
    program Information: 0 : 14.3.2013 20:31:22 INFO:[AppXPackage::GetPackageFromManifestFile]Loading applications.
    program Information: 0 : 14.3.2013 20:31:22 INFO:[AppXPackage::GetPackageFromManifestFile]Number of applications: 1.
    program Information: 0 : 14.3.2013 20:31:22 INFO:[AppXPackage::GetPackageFromManifestFile]New Application.
    program Information: 0 : 14.3.2013 20:31:22 INFO:[AppXPackage::GetPackageFromManifestFile]Loading id.
    program Information: 0 : 14.3.2013 20:31:22 INFO:[AppXPackage::GetPackageFromManifestFile]Id: App.
    program Information: 0 : 14.3.2013 20:31:22 INFO:[AppXPackage::GetPackageFromManifestFile]EntryPointType: MCA
    program Information: 0 : 14.3.2013 20:31:22 INFO:[AppXPackage::GetPackageFromManifestFile]Entrypoint value = App6.exe
    program Information: 0 : 14.3.2013 20:31:22 INFO:[AppXPackage::GetPackageFromManifestFile]Creating application node.
    program Information: 0 : 14.3.2013 20:31:22 INFO:[AppXPackage::GetPackageFromManifestFile]node created.
    program Information: 0 : 14.3.2013 20:31:22 INFO:[SystemConfigure::Setup]Retrieving active power scheme.
    program Information: 0 : 14.3.2013 20:31:22 INFO:[SystemConfigure::GetCurrentPowerScheme]Current Power Scheme Guid : 381b4222-f694-41f0-9685-ff5bb260df2e
    program Information: 0 : 14.3.2013 20:31:22 INFO:[SystemConfigure::Setup]Original power scheme guid: 381b4222-f694-41f0-9685-ff5bb260df2e
    program Information: 0 : 14.3.2013 20:31:22 INFO:[SystemConfigure::Setup]Duplicating active power scheme.
    program Information: 0 : 14.3.2013 20:31:22 INFO:[SystemConfigure::Setup]duplicate power scheme guid: d90d536d-04b9-4436-bcc5-fbe2733b0f00
    program Information: 0 : 14.3.2013 20:31:22 INFO:[SystemConfigure::Setup]Setting duplicate guid: d90d536d-04b9-4436-bcc5-fbe2733b0f00 to active.
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -S d90d536d-04b9-4436-bcc5-fbe2733b0f00
    program Information: 0 : 14.3.2013 20:31:22 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:31:23 INFO:[SystemConfigure::Setup]setting monitor timeout values.
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -X -monitor-timeout-ac 999
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -X -monitor-timeout-dc 999
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:31:23 INFO:[SystemConfigure::Setup]setting disk timeout values.
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -X -disk-timeout-ac 999
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -X -disk-timeout-dc 999
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:31:23 INFO:[SystemConfigure::Setup]setting standby timeout values.
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -X -standby-timeout-ac 999
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -X -standby-timeout-dc 999
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:31:23 INFO:[SystemConfigure::Setup]setting hibernate timeout values.
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -X -hibernate-timeout-ac 999
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -X -hibernate-timeout-dc 999
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:31:23 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:31:23 INFO:[SystemConfigure::Setup]Turning off screen saver.
    program Information: 0 : 14.3.2013 20:31:23 INFO:The app 9bc8b338-6b8f-4d01-8f6d-87d7a1846bab_1.0.0.0_neutral__z9vwgxca2s5bp is not in running state.
    program Information: 0 : 14.3.2013 20:31:23 INFO:[NGenTest]PackageFullName value is : 9bc8b338-6b8f-4d01-8f6d-87d7a1846bab_1.0.0.0_neutral__z9vwgxca2s5bp
    program Information: 0 : 14.3.2013 20:31:23 INFO:[NGenTest]32 Bit Ngen - C:\Program Files\WindowsApps\9bc8b338-6b8f-4d01-8f6d-87d7a1846bab_1.0.0.0_neutral__z9vwgxca2s5bp\App6.exe
    program Information: 0 : 14.3.2013 20:31:23 INFO:[NGenTest]NGen Command: C:\Windows\Microsoft.NET\Framework\v4.0.30319\ngen.exe install "C:\Program Files\WindowsApps\9bc8b338-6b8f-4d01-8f6d-87d7a1846bab_1.0.0.0_neutral__z9vwgxca2s5bp\App6.exe" /NoDependencies /noroot /version:v4.0.30319 /package:"9bc8b338-6b8f-4d01-8f6d-87d7a1846bab_1.0.0.0_neutral__z9vwgxca2s5bp" /localappdata:"C:\Users\Onur\AppData\Local\Packages\9bc8b338-6b8f-4d01-8f6d-87d7a1846bab_z9vwgxca2s5bp\AC" /nologo 
    program Information: 0 : 14.3.2013 20:31:23 INFO:[NGenTest]NGen Command Output:
    program Information: 0 : 14.3.2013 20:31:24 INFO:[NGenTest]1>    Compiling assembly C:\Program Files\WindowsApps\9bc8b338-6b8f-4d01-8f6d-87d7a1846bab_1.0.0.0_neutral__z9vwgxca2s5bp\App6.exe (CLR v4.0.30319) ...
    program Information: 0 : 14.3.2013 20:31:24 INFO:[NGenTest]NGen Command Output end
    program Information: 0 : 14.3.2013 20:31:24 INFO:[NGenTest]64 Bit Ngen - C:\Program Files\WindowsApps\9bc8b338-6b8f-4d01-8f6d-87d7a1846bab_1.0.0.0_neutral__z9vwgxca2s5bp\App6.exe
    program Information: 0 : 14.3.2013 20:31:24 INFO:[NGenTest]NGen Command: C:\Windows\Microsoft.NET\Framework64\v4.0.30319\ngen.exe install "C:\Program Files\WindowsApps\9bc8b338-6b8f-4d01-8f6d-87d7a1846bab_1.0.0.0_neutral__z9vwgxca2s5bp\App6.exe" /NoDependencies /noroot /version:v4.0.30319 /package:"9bc8b338-6b8f-4d01-8f6d-87d7a1846bab_1.0.0.0_neutral__z9vwgxca2s5bp" /localappdata:"C:\Users\Onur\AppData\Local\Packages\9bc8b338-6b8f-4d01-8f6d-87d7a1846bab_z9vwgxca2s5bp\AC" /nologo 
    program Information: 0 : 14.3.2013 20:31:24 INFO:[NGenTest]NGen Command Output:
    program Information: 0 : 14.3.2013 20:31:24 INFO:[NGenTest]1>    Compiling assembly C:\Program Files\WindowsApps\9bc8b338-6b8f-4d01-8f6d-87d7a1846bab_1.0.0.0_neutral__z9vwgxca2s5bp\App6.exe (CLR v4.0.30319) ...
    program Information: 0 : 14.3.2013 20:31:24 INFO:[NGenTest]NGen Command Output end
    program Information: 0 : 14.3.2013 20:31:24 INFO:[NGenTest]NGENPackage completed successfully.
    program Information: 0 : 14.3.2013 20:31:24 INFO:[Performance::RunTest]Launch once to negate cold launches in the workload
    program Information: 0 : 14.3.2013 20:31:24 INFO:[AppXApplication::Launch]Launching Application ID App.
    program Information: 0 : 14.3.2013 20:31:24 INFO:[AppXApplication::Launch]Launching 9bc8b338-6b8f-4d01-8f6d-87d7a1846bab_z9vwgxca2s5bp!App
    program Information: 0 : 14.3.2013 20:31:24 INFO:[AppXApplication::Launch]process id = 1524
    program Information: 0 : 14.3.2013 20:31:24 INFO:[Performance::RunTest]Sleeping for 10000 msec.
    program Information: 0 : 14.3.2013 20:31:34 INFO:[Performance::RunTest]Suspend once to negate cold suspends in the workload
    program Information: 0 : 14.3.2013 20:31:34 INFO:Switching back to desktop.
    program Information: 0 : 14.3.2013 20:31:45 INFO:[Performance::RunTest]Sleeping for 10000 msec.
    program Information: 0 : 14.3.2013 20:31:55 INFO:[Performance::RunTest]Starting Workload.
    program Information: 0 : 14.3.2013 20:31:55 INFO:[Performance::StartXperf]Starting xperf
    program Information: 0 : 14.3.2013 20:31:55 INFO:[Performance::StartXperf]Total Physical Memory is 3,99 GB
    program Information: 0 : 14.3.2013 20:31:55 INFO:[XperfAction::On]The following two xperf -stop commands are expected to fail since xperf should not be running.
    program Information: 0 : 14.3.2013 20:31:55 INFO:[XperfAction::Run]Starting Xperf with arguments: -stop uSession
    program Information: 0 : 14.3.2013 20:31:55 INFO:[ProcessAction::Run]Starting Run of: C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\..\Windows Performance Toolkit\xperf.exe with arguments -stop uSession
    program Information: 0 : 14.3.2013 20:31:55 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 300000
    program Information: 0 : 14.3.2013 20:31:55 INFO:[ProcessAction::myProcess_ErrorDataReceived]error data: xperf: error: uSession: The instance name passed was not recognized as valid by a WMI data provider. (0x1069).
    program Information: 0 : 14.3.2013 20:31:55 INFO:[ProcessAction::WaitForExit]Exitcode: -2147020695
    program Information: 0 : 14.3.2013 20:31:55 INFO:[XperfAction::Run]Xperf output: 
    program Information: 0 : 14.3.2013 20:31:55 INFO:[XperfAction::Run]Starting Xperf with arguments: -stop
    program Information: 0 : 14.3.2013 20:31:55 INFO:[ProcessAction::Run]Starting Run of: C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\..\Windows Performance Toolkit\xperf.exe with arguments -stop
    program Information: 0 : 14.3.2013 20:31:55 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 300000
    program Information: 0 : 14.3.2013 20:31:55 INFO:[ProcessAction::myProcess_ErrorDataReceived]error data: xperf: error: NT Kernel Logger: The instance name passed was not recognized as valid by a WMI data provider. (0x1069).
    program Information: 0 : 14.3.2013 20:31:55 INFO:[ProcessAction::WaitForExit]Exitcode: -2147020695
    program Information: 0 : 14.3.2013 20:31:55 INFO:[XperfAction::Run]Xperf output: 
    program Information: 0 : 14.3.2013 20:31:55 INFO:[XperfAction::On]Starting xperf user and kernel sessions.
    program Information: 0 : 14.3.2013 20:31:55 INFO:[XperfAction::Run]Starting Xperf with arguments: -on Base+FILE_IO+FILE_IO_INIT+CSWITCH+MEMORY -Buffersize 1024 -MinBuffers 60 -MaxBuffers 60 -f "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\kernel.etl" -start uSession -on Microsoft-Windows-Immersive-Shell+Win32HeapRanges -Buffersize 1024 -MinBuffers 10 -MaxBuffers 10 -f "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\user.etl"
    program Information: 0 : 14.3.2013 20:31:55 INFO:[ProcessAction::Run]Starting Run of: C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\..\Windows Performance Toolkit\xperf.exe with arguments -on Base+FILE_IO+FILE_IO_INIT+CSWITCH+MEMORY -Buffersize 1024 -MinBuffers 60 -MaxBuffers 60 -f "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\kernel.etl" -start uSession -on Microsoft-Windows-Immersive-Shell+Win32HeapRanges -Buffersize 1024 -MinBuffers 10 -MaxBuffers 10 -f "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\user.etl"
    program Information: 0 : 14.3.2013 20:31:55 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 300000
    program Information: 0 : 14.3.2013 20:31:55 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:31:55 INFO:[XperfAction::Run]Xperf output: 
    program Information: 0 : 14.3.2013 20:31:55 INFO:[Performance::StartXperf]Setting start mark.
    program Information: 0 : 14.3.2013 20:31:55 INFO:[XperfAction::Run]Starting Xperf with arguments: -m ProgramStart
    program Information: 0 : 14.3.2013 20:31:55 INFO:[ProcessAction::Run]Starting Run of: C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\..\Windows Performance Toolkit\xperf.exe with arguments -m ProgramStart
    program Information: 0 : 14.3.2013 20:31:55 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 300000
    program Information: 0 : 14.3.2013 20:31:55 INFO:[ProcessAction::myProcess_ErrorDataReceived]error data: Mark: ProgramStart
    program Information: 0 : 14.3.2013 20:31:55 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:31:55 INFO:[XperfAction::Run]Xperf output: 
    program Information: 0 : 14.3.2013 20:31:55 INFO:[AppXApplication::Launch]Launching Application ID App.
    program Information: 0 : 14.3.2013 20:31:55 INFO:[AppXApplication::Launch]Launching 9bc8b338-6b8f-4d01-8f6d-87d7a1846bab_z9vwgxca2s5bp!App
    program Information: 0 : 14.3.2013 20:31:55 INFO:[AppXApplication::Launch]process id = 5116
    program Information: 0 : 14.3.2013 20:31:55 INFO:[Performance::RunLaunchWorkload]Sleeping for 10000 msec.
    program Information: 0 : 14.3.2013 20:32:05 INFO:[Performance::StopXperf]Setting end mark.
    program Information: 0 : 14.3.2013 20:32:05 INFO:[XperfAction::Run]Starting Xperf with arguments: -m ProgramEnd
    program Information: 0 : 14.3.2013 20:32:05 INFO:[ProcessAction::Run]Starting Run of: C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\..\Windows Performance Toolkit\xperf.exe with arguments -m ProgramEnd
    program Information: 0 : 14.3.2013 20:32:05 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 300000
    program Information: 0 : 14.3.2013 20:32:06 INFO:[ProcessAction::myProcess_ErrorDataReceived]error data: Mark: ProgramEnd
    program Information: 0 : 14.3.2013 20:32:06 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:32:06 INFO:[XperfAction::Run]Xperf output: 
    program Information: 0 : 14.3.2013 20:32:06 INFO:[Performance::StopXperf]Stopping xperf.
    program Information: 0 : 14.3.2013 20:32:06 INFO:[XperfAction::Stop]Stopping xperf session and producing merged etl
    program Information: 0 : 14.3.2013 20:32:06 INFO:[XperfAction::Run]Starting Xperf with arguments: -stop -stop uSession -d "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl"
    program Information: 0 : 14.3.2013 20:32:06 INFO:[ProcessAction::Run]Starting Run of: C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\..\Windows Performance Toolkit\xperf.exe with arguments -stop -stop uSession -d "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl"
    program Information: 0 : 14.3.2013 20:32:06 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 300000
    program Information: 0 : 14.3.2013 20:32:16 INFO:[ProcessAction::myProcess_OutputDataRecieved]output data: Merged Etl: C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl
    program Information: 0 : 14.3.2013 20:32:16 INFO:[ProcessAction::myProcess_OutputDataRecieved]output data: The trace you have just captured "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl" may contain personally identifiable information, including but not necessarily limited to paths to files accessed, paths to registry accessed and process names. Exact information depends on the events that were logged. Please be aware of this when sharing out this trace with other people.
    program Information: 0 : 14.3.2013 20:32:16 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:32:16 INFO:[XperfAction::Run]Xperf output: Merged Etl: C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl
    The trace you have just captured "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl" may contain personally identifiable information, including but not necessarily limited to paths to files accessed, paths to registry accessed and process names. Exact information depends on the events that were logged. Please be aware of this when sharing out this trace with other people.

    program Information: 0 : 14.3.2013 20:32:16 INFO:[Performance::RunTest]Making sure the app is stopped, ignore any failure.
    program Information: 0 : 14.3.2013 20:32:16 INFO:Switching back to desktop.
    program Information: 0 : 14.3.2013 20:32:26 INFO:Switching back to desktop.
    program Information: 0 : 14.3.2013 20:32:26 INFO:[PostProcessing::GetLaunchTimes]Dumping events from etlfile C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl.
    program Information: 0 : 14.3.2013 20:32:26 INFO:[XperfAction::Run]Starting Xperf with arguments: -i "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl" -o "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\xperfTemp.txt" -a dumper -provider {315a8872-923e-4ea2-9889-33cd4754bf64}
    program Information: 0 : 14.3.2013 20:32:26 INFO:[ProcessAction::Run]Starting Run of: C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\..\Windows Performance Toolkit\xperf.exe with arguments -i "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl" -o "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\xperfTemp.txt" -a dumper -provider {315a8872-923e-4ea2-9889-33cd4754bf64}
    program Information: 0 : 14.3.2013 20:32:26 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 300000
    program Information: 0 : 14.3.2013 20:32:28 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:32:28 INFO:[XperfAction::Run]Xperf output: 
    program Information: 0 : 14.3.2013 20:32:28 INFO:[PostProcessing::ReadLaunchTimes]Retrieving activation start and stop from file C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\xperfTemp.txt
    program Information: 0 : 14.3.2013 20:32:28 INFO:[PostProcessing::ReadLaunchTimes]found perftrack start: microsoft-windows-ımmersive-shell/perftrack_splashscreen_appshown/win:start,     231935,     explorer.exe (1308),        352,   0, , , , , 10, "9bc8b338-6b8f-4d01-8f6d-87d7a1846bab_z9vwgxca2s5bp!app", "9bc8b338-6b8f-4d01-8f6d-87d7a1846bab_1.0.0.0_neutral__z9vwgxca2s5bp", "windows.launch"
    program Information: 0 : 14.3.2013 20:32:28 INFO:[PostProcessing::ReadLaunchTimes]found perftrack stop: microsoft-windows-ımmersive-shell/perftrack_splashscreen_appshown/win:stop,    1188613,     explorer.exe (1308),        352,   1, , , , , 10, "9bc8b338-6b8f-4d01-8f6d-87d7a1846bab_z9vwgxca2s5bp!app", "9bc8b338-6b8f-4d01-8f6d-87d7a1846bab_1.0.0.0_neutral__z9vwgxca2s5bp", "windows.launch", 1
    program Information: 0 : 14.3.2013 20:32:28 INFO:[PostProcessing::GetPrivatePages]Gathering xml for private pages.
    program Information: 0 : 14.3.2013 20:32:28 INFO:[XperfAction::Run]Starting Xperf with arguments: -i "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl" -o "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\xperfTemp.xml" -a residentset -xml
    program Information: 0 : 14.3.2013 20:32:28 INFO:[ProcessAction::Run]Starting Run of: C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\..\Windows Performance Toolkit\xperf.exe with arguments -i "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl" -o "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\xperfTemp.xml" -a residentset -xml
    program Information: 0 : 14.3.2013 20:32:28 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 300000
    program Information: 0 : 14.3.2013 20:32:35 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:32:35 INFO:[XperfAction::Run]Xperf output: 
    program Information: 0 : 14.3.2013 20:32:35 INFO:[PostProcessing::CalcPrivatePages]Retrieving private page usage.
    program Information: 0 : 14.3.2013 20:32:35 INFO:[PostProcessing::CalcPrivatePages]Finding the memory node.
    program Information: 0 : 14.3.2013 20:32:35 INFO:[PostProcessing::CalcPrivatePages]Page size = 4096
    program Information: 0 : 14.3.2013 20:32:35 INFO:[PostProcessing::CalcPrivatePages]Finding the process detail nodes.
    program Information: 0 : 14.3.2013 20:32:35 INFO:[PostProcessing::CalcPrivatePages]number of nodes = 0
    program Information: 0 : 14.3.2013 20:32:35 INFO:[PostProcessing::CalcPrivatePages]Total size = 0
    program Information: 0 : 14.3.2013 20:32:35 INFO:[PostProcessing::GetCPUPercentage]Gathering summary table info for cpu usage.
    program Information: 0 : 14.3.2013 20:32:35 INFO:[XperfAction::Run]Starting Xperf with arguments: -i "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl" -o "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\xperfTemp.txt" -loadaddin "C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\perf_exporter.dll" -a summarytable -export "Context Switch" -range 231935 1188613 -nogroup -columns "Process Name" "Display Name" "Process" "% Cpu Usage"
    program Information: 0 : 14.3.2013 20:32:35 INFO:[ProcessAction::Run]Starting Run of: C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\..\Windows Performance Toolkit\xperf.exe with arguments -i "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl" -o "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\xperfTemp.txt" -loadaddin "C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\perf_exporter.dll" -a summarytable -export "Context Switch" -range 231935 1188613 -nogroup -columns "Process Name" "Display Name" "Process" "% Cpu Usage"
    program Information: 0 : 14.3.2013 20:32:36 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 300000
    program Information: 0 : 14.3.2013 20:32:36 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:32:36 INFO:[XperfAction::Run]Xperf output: 
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]Retrieving CPU percentages.
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]Search name = App6.exe
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),5.60
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),2.97
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),1.84
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),0.77
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),0.54
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),0.43
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),0.23
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),0.15
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),0.12
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),0.05
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),0.04
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),0.03
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),0.02
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),0.01
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),0.01
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),0.01
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),0.01
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),0.00
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),0.00
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),0.00
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::CalcCPUPercentage]CPU percent app6.exe,app6.exe,app6.exe <9bc8b338-6b8f-4d01-8f6d-87d7a1846bab> (5116),0.00
    program Information: 0 : 14.3.2013 20:32:36 INFO:[PostProcessing::GetFileIO]Gathering summary table info for file IO.
    program Information: 0 : 14.3.2013 20:32:36 INFO:[XperfAction::Run]Starting Xperf with arguments: -i "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl" -o "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\xperfTemp.txt" -loadaddin "C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\perf_exporter.dll" -a summarytable -export FileIO -range 231935 1188613 -nogroup -columns "Process Name" "Logging Process" Size
    program Information: 0 : 14.3.2013 20:32:36 INFO:[ProcessAction::Run]Starting Run of: C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\..\Windows Performance Toolkit\xperf.exe with arguments -i "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl" -o "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\xperfTemp.txt" -loadaddin "C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\perf_exporter.dll" -a summarytable -export FileIO -range 231935 1188613 -nogroup -columns "Process Name" "Logging Process" Size
    program Information: 0 : 14.3.2013 20:32:36 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 300000
    program Information: 0 : 14.3.2013 20:32:37 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:32:37 INFO:[XperfAction::Run]Xperf output: 
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]Retrieving File IO usage.
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,616
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,616
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,616
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,4096
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,4096
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,616
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,4096
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,616
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,616
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,4096
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,616
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,4096
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,616
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,4096
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,616
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,4096
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,616
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,4096
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,616
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,4096
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,3
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,200
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,2380
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,3232
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,356
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,2096
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,324
    program Information: 0 : 14.3.2013 20:32:37 INFO:[PostProcessing::CalcFileIO]File IO app6.exe,5116,736
    program Information: 0 : 14.3.2013 20:32:37 INFO:[Performance::GetResultAndLogMessages]Launch Time = 0,956678
    program Information: 0 : 14.3.2013 20:32:37 INFO:[Performance::GetResultAndLogMessages]Suspend Time = 0
    program Information: 0 : 14.3.2013 20:32:37 INFO:[Performance::GetResultAndLogMessages]Cpu Percent = 12,83
    program Information: 0 : 14.3.2013 20:32:37 INFO:[Performance::GetResultAndLogMessages]File IO = 0,0505132675170898
    program Information: 0 : 14.3.2013 20:32:37 INFO:[Performance::GetResultAndLogMessages]Private page size = 0
    program Information: 0 : 14.3.2013 20:32:37 INFO:[Performance::RunTest]Making sure the app is stopped, ignore any failure.
    program Information: 0 : 14.3.2013 20:32:37 INFO:Switching back to desktop.
    program Information: 0 : 14.3.2013 20:32:38 INFO:Switching back to desktop.
    Invoking method [Cleanup]
    program Information: 0 : 14.3.2013 20:32:38 INFO:[Performance::Cleanup]Restoring system power configuration.
    program Information: 0 : 14.3.2013 20:32:38 INFO:[SystemConfigure::Cleanup]Setting original guid: 381b4222-f694-41f0-9685-ff5bb260df2e to active.
    program Information: 0 : 14.3.2013 20:32:38 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -S 381b4222-f694-41f0-9685-ff5bb260df2e
    program Information: 0 : 14.3.2013 20:32:38 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:32:38 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:32:38 INFO:[SystemConfigure::Cleanup]Deleting duplicate guid.
    program Information: 0 : 14.3.2013 20:32:38 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -DELETE d90d536d-04b9-4436-bcc5-fbe2733b0f00
    program Information: 0 : 14.3.2013 20:32:38 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:32:38 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:32:38 INFO:[SystemConfigure::Cleanup]Reseting screen saver.



    • Edited by OnurYIL Thursday, March 14, 2013 7:18 PM
    Thursday, March 14, 2013 5:44 PM
  • Packaged with signed in:

    program Information: 0 : 14.3.2013 20:25:26 INFO:[Configuration:LoadSettings]overridepath - %localappdata%\Microsoft\AppCertKitPerfConfig
    program Information: 0 : 14.3.2013 20:25:26 INFO:[Configuration:LoadSettings]disablexperf - false
    program Information: 0 : 14.3.2013 20:25:26 INFO:[Configuration:LoadSettings]xperfpath - ..\Windows Performance Toolkit\xperf.exe
    program Information: 0 : 14.3.2013 20:25:26 INFO:[Configuration:LoadSettings]xperfflags - Base+FILE_IO+FILE_IO_INIT+CSWITCH+MEMORY
    program Information: 0 : 14.3.2013 20:25:26 INFO:[Configuration:LoadSettings]xperfuserflags - Microsoft-Windows-Immersive-Shell+Win32HeapRanges
    program Information: 0 : 14.3.2013 20:25:26 INFO:[Configuration:LoadSettings]xperfminbuffers - 10
    program Information: 0 : 14.3.2013 20:25:26 INFO:[Configuration:LoadSettings]xperftimeoutmsec - 300000
    program Information: 0 : 14.3.2013 20:25:26 INFO:[Configuration:LoadSettings]systemcfgtimeoutmsec - 10000
    program Information: 0 : 14.3.2013 20:25:26 INFO:[Configuration:LoadSettings]runngen - true
    program Information: 0 : 14.3.2013 20:25:26 INFO:[Configuration:LoadSettings]launchtesttimemsec - 10000
    program Information: 0 : 14.3.2013 20:25:26 INFO:[Configuration:LoadSettings]suspendtesttimemsec - 15000
    program Information: 0 : 14.3.2013 20:25:26 INFO:[Configuration:LoadSettings]launchwarningsec - 3
    program Information: 0 : 14.3.2013 20:25:26 INFO:[Configuration:LoadSettings]launchfailsec - 5
    program Information: 0 : 14.3.2013 20:25:26 INFO:[Configuration:LoadSettings]maxcpuusagepercent - 50
    program Information: 0 : 14.3.2013 20:25:26 INFO:[Configuration:LoadSettings]maxlaunchfileiomb - 50
    program Information: 0 : 14.3.2013 20:25:26 INFO:[Configuration:LoadSettings]maxsuspendfileiomb - 20
    program Information: 0 : 14.3.2013 20:25:26 INFO:[Configuration:LoadSettings]maxprivatepagesmb - 25
    program Information: 0 : 14.3.2013 20:25:26 INFO:[Configuration:LoadSettings]suspendfailsec - 2
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ConfigFile::GetConfig]Override path = C:\Users\Onur\AppData\Local\Microsoft\AppCertKitPerfConfig\Microsoft.Windows.SoftwareLogo.Performance.dll.config
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ConfigFile::ReadStringEntry]Reading entry: xperfpath
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ConfigFile::ReadStringEntry]Reading entry: disablexperf
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ConfigFile::ReadStringEntry]Reading entry: xperfflags
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ConfigFile::ReadStringEntry]Reading entry: xperfuserflags
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ConfigFile::ReadStringEntry]Reading entry: xperfminbuffers
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ConfigFile::ReadStringEntry]Reading entry: xperftimeoutmsec
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ConfigFile::ReadStringEntry]Reading entry: systemcfgtimeoutmsec
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ConfigFile::ReadStringEntry]Reading entry: runNgen
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ConfigFile::ReadStringEntry]Reading entry: launchtesttimemsec
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ConfigFile::ReadStringEntry]Reading entry: suspendtesttimemsec
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ConfigFile::ReadStringEntry]Reading entry: launchwarningsec
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ConfigFile::ReadStringEntry]Reading entry: launchfailsec
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ConfigFile::ReadStringEntry]Reading entry: maxcpuusagepercent
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ConfigFile::ReadStringEntry]Reading entry: maxlaunchfileioMB
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ConfigFile::ReadStringEntry]Reading entry: maxsuspendfileioMB
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ConfigFile::ReadStringEntry]Reading entry: maxprivatepagesMB
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ConfigFile::ReadStringEntry]Reading entry: suspendfailsec
    program Information: 0 : 14.3.2013 20:25:26 INFO:[AppXPackage::GetPackageFromManifestFile]Loading Manifest.
    program Information: 0 : 14.3.2013 20:25:26 INFO:[AppXPackage::GetPackageFromManifestFile]Finding the Package node.
    program Information: 0 : 14.3.2013 20:25:26 INFO:[AppXPackage::GetPackageFromManifestFile]Creating namespace and identity node.
    program Information: 0 : 14.3.2013 20:25:26 INFO:[AppXPackage::GetPackageFromManifestFile]Loading applications.
    program Information: 0 : 14.3.2013 20:25:26 INFO:[AppXPackage::GetPackageFromManifestFile]Number of applications: 1.
    program Information: 0 : 14.3.2013 20:25:26 INFO:[AppXPackage::GetPackageFromManifestFile]New Application.
    program Information: 0 : 14.3.2013 20:25:26 INFO:[AppXPackage::GetPackageFromManifestFile]Loading id.
    program Information: 0 : 14.3.2013 20:25:26 INFO:[AppXPackage::GetPackageFromManifestFile]Id: App.
    program Information: 0 : 14.3.2013 20:25:26 INFO:[AppXPackage::GetPackageFromManifestFile]EntryPointType: MCA
    program Information: 0 : 14.3.2013 20:25:26 INFO:[AppXPackage::GetPackageFromManifestFile]Entrypoint value = App5.exe
    program Information: 0 : 14.3.2013 20:25:26 INFO:[AppXPackage::GetPackageFromManifestFile]Creating application node.
    program Information: 0 : 14.3.2013 20:25:26 INFO:[AppXPackage::GetPackageFromManifestFile]node created.
    program Information: 0 : 14.3.2013 20:25:26 INFO:[SystemConfigure::Setup]Retrieving active power scheme.
    program Information: 0 : 14.3.2013 20:25:26 INFO:[SystemConfigure::GetCurrentPowerScheme]Current Power Scheme Guid : 381b4222-f694-41f0-9685-ff5bb260df2e
    program Information: 0 : 14.3.2013 20:25:26 INFO:[SystemConfigure::Setup]Original power scheme guid: 381b4222-f694-41f0-9685-ff5bb260df2e
    program Information: 0 : 14.3.2013 20:25:26 INFO:[SystemConfigure::Setup]Duplicating active power scheme.
    program Information: 0 : 14.3.2013 20:25:26 INFO:[SystemConfigure::Setup]duplicate power scheme guid: d273ef77-2d03-4117-a907-86112820172e
    program Information: 0 : 14.3.2013 20:25:26 INFO:[SystemConfigure::Setup]Setting duplicate guid: d273ef77-2d03-4117-a907-86112820172e to active.
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -S d273ef77-2d03-4117-a907-86112820172e
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:25:26 INFO:[SystemConfigure::Setup]setting monitor timeout values.
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -X -monitor-timeout-ac 999
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -X -monitor-timeout-dc 999
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:25:26 INFO:[SystemConfigure::Setup]setting disk timeout values.
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -X -disk-timeout-ac 999
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -X -disk-timeout-dc 999
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:25:26 INFO:[SystemConfigure::Setup]setting standby timeout values.
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -X -standby-timeout-ac 999
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -X -standby-timeout-dc 999
    program Information: 0 : 14.3.2013 20:25:26 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:25:27 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:25:27 INFO:[SystemConfigure::Setup]setting hibernate timeout values.
    program Information: 0 : 14.3.2013 20:25:27 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -X -hibernate-timeout-ac 999
    program Information: 0 : 14.3.2013 20:25:27 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:25:27 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:25:27 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -X -hibernate-timeout-dc 999
    program Information: 0 : 14.3.2013 20:25:27 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:25:27 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:25:27 INFO:[SystemConfigure::Setup]Turning off screen saver.
    program Information: 0 : 14.3.2013 20:25:27 INFO:The app 25226OnurYILDIRIM.Islemci_1.0.0.0_neutral__sw22zamrfx42m is not in running state.
    program Information: 0 : 14.3.2013 20:25:27 INFO:[NGenTest]PackageFullName value is : 25226OnurYILDIRIM.Islemci_1.0.0.0_neutral__sw22zamrfx42m
    program Information: 0 : 14.3.2013 20:25:27 INFO:[NGenTest]32 Bit Ngen - C:\Program Files\WindowsApps\25226OnurYILDIRIM.Islemci_1.0.0.0_neutral__sw22zamrfx42m\App5.exe
    program Information: 0 : 14.3.2013 20:25:27 INFO:[NGenTest]NGen Command: C:\Windows\Microsoft.NET\Framework\v4.0.30319\ngen.exe install "C:\Program Files\WindowsApps\25226OnurYILDIRIM.Islemci_1.0.0.0_neutral__sw22zamrfx42m\App5.exe" /NoDependencies /noroot /version:v4.0.30319 /package:"25226OnurYILDIRIM.Islemci_1.0.0.0_neutral__sw22zamrfx42m" /localappdata:"C:\Users\Onur\AppData\Local\Packages\25226onuryildirim.islemci_sw22zamrfx42m\AC" /nologo 
    program Information: 0 : 14.3.2013 20:25:27 INFO:[NGenTest]NGen Command Output:
    program Information: 0 : 14.3.2013 20:25:27 INFO:[NGenTest]1>    Compiling assembly C:\Program Files\WindowsApps\25226OnurYILDIRIM.Islemci_1.0.0.0_neutral__sw22zamrfx42m\App5.exe (CLR v4.0.30319) ...
    program Information: 0 : 14.3.2013 20:25:27 INFO:[NGenTest]NGen Command Output end
    program Information: 0 : 14.3.2013 20:25:27 INFO:[NGenTest]64 Bit Ngen - C:\Program Files\WindowsApps\25226OnurYILDIRIM.Islemci_1.0.0.0_neutral__sw22zamrfx42m\App5.exe
    program Information: 0 : 14.3.2013 20:25:27 INFO:[NGenTest]NGen Command: C:\Windows\Microsoft.NET\Framework64\v4.0.30319\ngen.exe install "C:\Program Files\WindowsApps\25226OnurYILDIRIM.Islemci_1.0.0.0_neutral__sw22zamrfx42m\App5.exe" /NoDependencies /noroot /version:v4.0.30319 /package:"25226OnurYILDIRIM.Islemci_1.0.0.0_neutral__sw22zamrfx42m" /localappdata:"C:\Users\Onur\AppData\Local\Packages\25226onuryildirim.islemci_sw22zamrfx42m\AC" /nologo 
    program Information: 0 : 14.3.2013 20:25:27 INFO:[NGenTest]NGen Command Output:
    program Information: 0 : 14.3.2013 20:25:27 INFO:[NGenTest]1>    Compiling assembly C:\Program Files\WindowsApps\25226OnurYILDIRIM.Islemci_1.0.0.0_neutral__sw22zamrfx42m\App5.exe (CLR v4.0.30319) ...
    program Information: 0 : 14.3.2013 20:25:28 INFO:[NGenTest]NGen Command Output end
    program Information: 0 : 14.3.2013 20:25:28 INFO:[NGenTest]NGENPackage completed successfully.
    program Information: 0 : 14.3.2013 20:25:28 INFO:[Performance::RunTest]Launch once to negate cold launches in the workload
    program Information: 0 : 14.3.2013 20:25:28 INFO:[AppXApplication::Launch]Launching Application ID App.
    program Information: 0 : 14.3.2013 20:25:28 INFO:[AppXApplication::Launch]Launching 25226OnurYILDIRIM.Islemci_sw22zamrfx42m!App
    program Information: 0 : 14.3.2013 20:25:28 INFO:[AppXApplication::Launch]process id = 4152
    program Information: 0 : 14.3.2013 20:25:28 INFO:[Performance::RunTest]Sleeping for 10000 msec.
    program Information: 0 : 14.3.2013 20:25:38 INFO:[Performance::RunTest]Suspend once to negate cold suspends in the workload
    program Information: 0 : 14.3.2013 20:25:38 INFO:Switching back to desktop.
    program Information: 0 : 14.3.2013 20:25:48 INFO:[Performance::RunTest]Sleeping for 10000 msec.
    program Information: 0 : 14.3.2013 20:25:58 INFO:[Performance::RunTest]Starting Workload.
    program Information: 0 : 14.3.2013 20:25:58 INFO:[Performance::StartXperf]Starting xperf
    program Information: 0 : 14.3.2013 20:25:58 INFO:[Performance::StartXperf]Total Physical Memory is 3,99 GB
    program Information: 0 : 14.3.2013 20:25:58 INFO:[XperfAction::On]The following two xperf -stop commands are expected to fail since xperf should not be running.
    program Information: 0 : 14.3.2013 20:25:58 INFO:[XperfAction::Run]Starting Xperf with arguments: -stop uSession
    program Information: 0 : 14.3.2013 20:25:58 INFO:[ProcessAction::Run]Starting Run of: C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\..\Windows Performance Toolkit\xperf.exe with arguments -stop uSession
    program Information: 0 : 14.3.2013 20:25:58 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 300000
    program Information: 0 : 14.3.2013 20:25:59 INFO:[ProcessAction::myProcess_ErrorDataReceived]error data: xperf: error: uSession: The instance name passed was not recognized as valid by a WMI data provider. (0x1069).
    program Information: 0 : 14.3.2013 20:25:59 INFO:[ProcessAction::WaitForExit]Exitcode: -2147020695
    program Information: 0 : 14.3.2013 20:25:59 INFO:[XperfAction::Run]Xperf output: 
    program Information: 0 : 14.3.2013 20:25:59 INFO:[XperfAction::Run]Starting Xperf with arguments: -stop
    program Information: 0 : 14.3.2013 20:25:59 INFO:[ProcessAction::Run]Starting Run of: C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\..\Windows Performance Toolkit\xperf.exe with arguments -stop
    program Information: 0 : 14.3.2013 20:25:59 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 300000
    program Information: 0 : 14.3.2013 20:25:59 INFO:[ProcessAction::myProcess_ErrorDataReceived]error data: xperf: error: NT Kernel Logger: The instance name passed was not recognized as valid by a WMI data provider. (0x1069).
    program Information: 0 : 14.3.2013 20:25:59 INFO:[ProcessAction::WaitForExit]Exitcode: -2147020695
    program Information: 0 : 14.3.2013 20:25:59 INFO:[XperfAction::Run]Xperf output: 
    program Information: 0 : 14.3.2013 20:25:59 INFO:[XperfAction::On]Starting xperf user and kernel sessions.
    program Information: 0 : 14.3.2013 20:25:59 INFO:[XperfAction::Run]Starting Xperf with arguments: -on Base+FILE_IO+FILE_IO_INIT+CSWITCH+MEMORY -Buffersize 1024 -MinBuffers 60 -MaxBuffers 60 -f "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\kernel.etl" -start uSession -on Microsoft-Windows-Immersive-Shell+Win32HeapRanges -Buffersize 1024 -MinBuffers 10 -MaxBuffers 10 -f "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\user.etl"
    program Information: 0 : 14.3.2013 20:25:59 INFO:[ProcessAction::Run]Starting Run of: C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\..\Windows Performance Toolkit\xperf.exe with arguments -on Base+FILE_IO+FILE_IO_INIT+CSWITCH+MEMORY -Buffersize 1024 -MinBuffers 60 -MaxBuffers 60 -f "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\kernel.etl" -start uSession -on Microsoft-Windows-Immersive-Shell+Win32HeapRanges -Buffersize 1024 -MinBuffers 10 -MaxBuffers 10 -f "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\user.etl"
    program Information: 0 : 14.3.2013 20:25:59 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 300000
    program Information: 0 : 14.3.2013 20:25:59 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:25:59 INFO:[XperfAction::Run]Xperf output: 
    program Information: 0 : 14.3.2013 20:25:59 INFO:[Performance::StartXperf]Setting start mark.
    program Information: 0 : 14.3.2013 20:25:59 INFO:[XperfAction::Run]Starting Xperf with arguments: -m ProgramStart
    program Information: 0 : 14.3.2013 20:25:59 INFO:[ProcessAction::Run]Starting Run of: C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\..\Windows Performance Toolkit\xperf.exe with arguments -m ProgramStart
    program Information: 0 : 14.3.2013 20:25:59 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 300000
    program Information: 0 : 14.3.2013 20:25:59 INFO:[ProcessAction::myProcess_ErrorDataReceived]error data: Mark: ProgramStart
    program Information: 0 : 14.3.2013 20:25:59 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:25:59 INFO:[XperfAction::Run]Xperf output: 
    program Information: 0 : 14.3.2013 20:25:59 INFO:[AppXApplication::Launch]Launching Application ID App.
    program Information: 0 : 14.3.2013 20:25:59 INFO:[AppXApplication::Launch]Launching 25226OnurYILDIRIM.Islemci_sw22zamrfx42m!App
    program Information: 0 : 14.3.2013 20:26:00 INFO:[AppXApplication::Launch]process id = 5088
    program Information: 0 : 14.3.2013 20:26:00 INFO:[Performance::RunLaunchWorkload]Sleeping for 10000 msec.
    program Information: 0 : 14.3.2013 20:26:10 INFO:[Performance::StopXperf]Setting end mark.
    program Information: 0 : 14.3.2013 20:26:10 INFO:[XperfAction::Run]Starting Xperf with arguments: -m ProgramEnd
    program Information: 0 : 14.3.2013 20:26:10 INFO:[ProcessAction::Run]Starting Run of: C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\..\Windows Performance Toolkit\xperf.exe with arguments -m ProgramEnd
    program Information: 0 : 14.3.2013 20:26:10 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 300000
    program Information: 0 : 14.3.2013 20:26:10 INFO:[ProcessAction::myProcess_ErrorDataReceived]error data: Mark: ProgramEnd
    program Information: 0 : 14.3.2013 20:26:10 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:26:10 INFO:[XperfAction::Run]Xperf output: 
    program Information: 0 : 14.3.2013 20:26:10 INFO:[Performance::StopXperf]Stopping xperf.
    program Information: 0 : 14.3.2013 20:26:10 INFO:[XperfAction::Stop]Stopping xperf session and producing merged etl
    program Information: 0 : 14.3.2013 20:26:10 INFO:[XperfAction::Run]Starting Xperf with arguments: -stop -stop uSession -d "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl"
    program Information: 0 : 14.3.2013 20:26:10 INFO:[ProcessAction::Run]Starting Run of: C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\..\Windows Performance Toolkit\xperf.exe with arguments -stop -stop uSession -d "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl"
    program Information: 0 : 14.3.2013 20:26:10 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 300000
    program Information: 0 : 14.3.2013 20:26:30 INFO:[ProcessAction::myProcess_OutputDataRecieved]output data: Merged Etl: C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl
    program Information: 0 : 14.3.2013 20:26:30 INFO:[ProcessAction::myProcess_OutputDataRecieved]output data: The trace you have just captured "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl" may contain personally identifiable information, including but not necessarily limited to paths to files accessed, paths to registry accessed and process names. Exact information depends on the events that were logged. Please be aware of this when sharing out this trace with other people.
    program Information: 0 : 14.3.2013 20:26:30 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:26:30 INFO:[XperfAction::Run]Xperf output: Merged Etl: C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl
    The trace you have just captured "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl" may contain personally identifiable information, including but not necessarily limited to paths to files accessed, paths to registry accessed and process names. Exact information depends on the events that were logged. Please be aware of this when sharing out this trace with other people.

    program Information: 0 : 14.3.2013 20:26:30 INFO:[Performance::RunTest]Making sure the app is stopped, ignore any failure.
    program Information: 0 : 14.3.2013 20:26:30 INFO:Switching back to desktop.
    program Information: 0 : 14.3.2013 20:26:40 INFO:Switching back to desktop.
    program Information: 0 : 14.3.2013 20:26:41 INFO:[PostProcessing::GetLaunchTimes]Dumping events from etlfile C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl.
    program Information: 0 : 14.3.2013 20:26:41 INFO:[XperfAction::Run]Starting Xperf with arguments: -i "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl" -o "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\xperfTemp.txt" -a dumper -provider {315a8872-923e-4ea2-9889-33cd4754bf64}
    program Information: 0 : 14.3.2013 20:26:41 INFO:[ProcessAction::Run]Starting Run of: C:\Program Files (x86)\Windows Kits\8.0\App Certification Kit\..\Windows Performance Toolkit\xperf.exe with arguments -i "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\App_LaunchData.etl" -o "C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\xperfTemp.txt" -a dumper -provider {315a8872-923e-4ea2-9889-33cd4754bf64}
    program Information: 0 : 14.3.2013 20:26:41 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 300000
    program Information: 0 : 14.3.2013 20:26:43 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:26:43 INFO:[XperfAction::Run]Xperf output: 
    program Information: 0 : 14.3.2013 20:26:43 INFO:[PostProcessing::ReadLaunchTimes]Retrieving activation start and stop from file C:\Users\Onur\AppData\Local\Microsoft\AppCertKit\xperfTemp.txt
    program Error: 0 : 14.3.2013 20:26:43 ERROR:[PostProcessing::ReadLaunchTimes]
    program Information: 0 : 14.3.2013 20:26:43 INFO:[Performance::GetResultAndLogMessages]Launch Time = 0
    program Information: 0 : 14.3.2013 20:26:43 INFO:[Performance::GetResultAndLogMessages]Suspend Time = 0
    program Information: 0 : 14.3.2013 20:26:43 INFO:[Performance::GetResultAndLogMessages]Cpu Percent = 0
    program Information: 0 : 14.3.2013 20:26:43 INFO:[Performance::GetResultAndLogMessages]File IO = 0
    program Information: 0 : 14.3.2013 20:26:43 INFO:[Performance::GetResultAndLogMessages]Private page size = 0
    program Information: 0 : 14.3.2013 20:26:43 INFO:[Performance::RunTest]Making sure the app is stopped, ignore any failure.
    program Information: 0 : 14.3.2013 20:26:43 INFO:Switching back to desktop.
    program Information: 0 : 14.3.2013 20:26:43 INFO:Switching back to desktop.
    Invoking method [Cleanup]
    program Information: 0 : 14.3.2013 20:26:43 INFO:[Performance::Cleanup]Restoring system power configuration.
    program Information: 0 : 14.3.2013 20:26:43 INFO:[SystemConfigure::Cleanup]Setting original guid: 381b4222-f694-41f0-9685-ff5bb260df2e to active.
    program Information: 0 : 14.3.2013 20:26:43 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -S 381b4222-f694-41f0-9685-ff5bb260df2e
    program Information: 0 : 14.3.2013 20:26:43 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:26:43 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:26:43 INFO:[SystemConfigure::Cleanup]Deleting duplicate guid.
    program Information: 0 : 14.3.2013 20:26:43 INFO:[ProcessAction::Run]Starting Run of: powercfg with arguments -DELETE d273ef77-2d03-4117-a907-86112820172e
    program Information: 0 : 14.3.2013 20:26:43 INFO:[ProcessAction::WaitForExit]Waiting for exit with timeout of 10000
    program Information: 0 : 14.3.2013 20:26:43 INFO:[ProcessAction::WaitForExit]Exitcode: 0
    program Information: 0 : 14.3.2013 20:26:43 INFO:[SystemConfigure::Cleanup]Reseting screen saver.

    Now please check the parts that I underlined, after those lines, things are changing and I can't understand it.  Both are just blank pages, nothing else changed, just new project -> create package..


    • Edited by OnurYIL Thursday, March 14, 2013 7:19 PM
    Thursday, March 14, 2013 7:16 PM
  • I am trying to involve someone familiar with this topic to further look at this issue. There might be some time delay. Appreciate your patience.
     
    Thank you for your understanding and support.

    Min Zhu
    MSDN Community Support | Feedback to us
    Develop and promote your apps in Windows Store
    Please remember to mark the replies as answers if they help and unmark them if they provide no help.

    Monday, March 18, 2013 6:20 AM
    Moderator
  • Hello OnurYIL,

    Are you by any chance running on a Turkish version of Windows? If yes, could you please try, as a test, to change your display language to English and run the WACK test again? (Add English in the Languages configuration and set it to be the top-most language. Then log off and on again and run WACK.)

    Please let me know whether this enabled you to pass the test on your machine.

    FYI: If this issue has been stopping you from submitting your app to the store, please just go ahead and submit it. From my past experience, this issue only happens on specific machines with specific language settings. The apps from affected customer did not fail the performance launch test during the certification.

    Cheers


    Helge Mahrt - Microsoft Developer Support Engineer - If my reply answers your question, please mark this post as answered.

    • Marked as answer by OnurYIL Sunday, April 21, 2013 4:21 PM
    Tuesday, March 19, 2013 9:05 AM
  • FWIW, I'm seeing similar results using the English machine settings. Apps created using the blank template with no modifications are taking 10 seconds to launch. If I deploy through VS to the simulator, the app opens nearly instantaneously, but takes about the same 10 seconds when deploying to local machine.

    FWIW, I do have the System Center 2012 Endpoint Protection running which could slow down the process I suppose.


    http://www.ThinqLinq.com http://www.LinqInAction.net - "LINQ In Action", The book is now available. Don't wait for the movie

    Tuesday, March 19, 2013 6:25 PM
  • Hello Jim,

    The symptoms you are describing are different from what OnurYIL described. If you need assistance with this, I'd like to encourage you to open a new topic for it.

    Cheers


    Helge Mahrt - Microsoft Developer Support Engineer - If my reply answers your question, please mark this post as answered.

    Wednesday, March 20, 2013 11:49 AM
  • Hi Onur,

    Sorry for late answering your question on thread I started, "http://social.msdn.microsoft.com/Forums/en-US/windowsstore/thread/a5a2dd0a-b941-4c01-bc90-86855f87b760", I've just seen your latest reply.

    This is exactly the same issue I've experienced and I had to spend a lot of time to understand what I was doing wrong. My app was rejected by Store testers a few times. I was developing my apps on Windows 8 RTM.

    Then I requested support from Microsoft since I couldn't get an exact solution by developer forums. They analyzed all my event logs and WACK reports I generated in my PC. Finally, as Helge said previously, we figured out that there is a defect in Turkish version of WACK(or maybe Windows 8, I'm not sure yet). Then I installed the English language pack to my other PC which operates Windows 8 Pro. So it is the only language and OS combination for me that allows WACK to pass my app. It still fails on Windows 8 RTM PC (in both languages) and Windows 8 Pro PC when display language is Turkish. So, I'm waiting a new update for WACK to fix this defect. 

    By the way, If you live in Ankara, we can meet somewhere to test your app in my PC. You can contact me by; thakanoduncu@outlook.com

    P.S: Write specific instructions to Store testers about this issue when you submit your app to Store. That's how I succeeded to publish my app on Store.

    Friday, March 22, 2013 2:30 PM
  • Hello OnurYIL,

    Have you been able to try using English as the display language, to see whether the issue still reproduces?

    Cheers


    Helge Mahrt - Microsoft Developer Support Engineer - If my reply answers your question, please mark this post as answered.

    Monday, April 1, 2013 10:14 AM
  • Hello everyone, 

    Sorry for the very very late answer. Thank you all for the answers. I submitted my app like Helge said even if fails on loca WACK. It rejected a few times but those were not because of anything releated to this issue, but some huge files loading. After a quick fix, I got my app on Store and it's doing quite well. However, I still have the issue with the WACK tool. I have an English version of Windows 8 Pro, downloaded from DreamSpark. But I also have a Win 7 installed on the same PC as dual boot. And it has a Turkish language pack installed. According to your posts, it might be the cause of the problem. I still don't know but since it do not stop me from submitting my apps, it's okay. So again, thanks all for the answers. By the way Tolga, thank you for your offer about testing the app on a different machine. But I live in Istanbul. I'm going to mark Helge's first answer as answer.

    Sunday, April 21, 2013 4:21 PM