Test case details
Test name:
OfficeDevPnP.Core.Tests.Framework.Functional.PropertyBagTests.WebPropertyBagAddingTest
Outcome:
Run at:
10/9/2019 2:02:17 AM
Duration:
00:00:11
Error message:
Test method OfficeDevPnP.Core.Tests.Framework.Functional.PropertyBagTests.WebPropertyBagAddingTest threw exception: Microsoft.SharePoint.Client.ServerException: There is no Web named "/sites/TestPnPSC_12345_286219e0-dba1-408d-a7d6-80f534397080/sub".
Stacktrace:
at at OfficeDevPnP.Core.Framework.Provisioning.ObjectHandlers.SiteToTemplateConversion.ApplyRemoteTemplate(Web web, ProvisioningTemplate template, ProvisioningTemplateApplyingInformation provisioningInfo, Boolean calledFromHierarchy, TokenParser tokenParser) in c:\pnpbuild\Core\OfficeDevPnP.Core\Framework\Provisioning\ObjectHandlers\SiteToTemplateConversion.cs:line 441 at Microsoft.SharePoint.Client.WebExtensions.ApplyProvisioningTemplate(Web web, ProvisioningTemplate template, ProvisioningTemplateApplyingInformation applyingInformation) in c:\pnpbuild\Core\OfficeDevPnP.Core\Extensions\WebExtensions.cs:line 1174 at OfficeDevPnP.Core.Tests.Framework.Functional.Implementation.ImplementationBase.TestProvisioningTemplate(ClientContext cc, String templateName, Handlers handlersToProcess, ProvisioningTemplateApplyingInformation ptai, ProvisioningTemplateCreationInformation ptci) in c:\pnpbuild\Core\OfficeDevPnP.Core.Tests\Framework\Functional\Implementation\ImplementationBase.cs:line 72 at OfficeDevPnP.Core.Tests.Framework.Functional.Implementation.PropertyBagImplementation.WebPropertyBagAdding(String url) in c:\pnpbuild\Core\OfficeDevPnP.Core.Tests\Framework\Functional\Implementation\PropertyBagImplementation.cs:line 128 at OfficeDevPnP.Core.Tests.Framework.Functional.PropertyBagTests.WebPropertyBagAddingTest() in c:\pnpbuild\Core\OfficeDevPnP.Core.Tests\Framework\Functional\PropertyBagTests.cs:line 56
Console output:
    Applying template - 1/2 - Initializing engine
Applying template - 2/2 - Property bag entries
Getting template - 1/1 - Property bag entries
-- Property Bags validation True
Applying template - 1/2 - Initializing engine
Applying template - 2/2 - Property bag entries
Getting template - 1/1 - Property bag entries
-- Property Bags validation True
Applying template - 1/2 - Initializing engine
Applying template - 2/2 - Property bag entries
Getting template - 1/1 - Property bag entries
-- Property Bags validation True
Applying template - 1/2 - Initializing engine
Applying template - 2/2 - Property bag entries
Type = Microsoft.SharePoint.Client.ServerException
TraceCorrelationId = d9060c9f-a0e5-1000-aec8-011299a717e1
Message = There is no Web named "/sites/TestPnPSC_12345_286219e0-dba1-408d-a7d6-80f534397080/sub".
Source = OfficeDevPnP.Core
ServerStackTrace = 
ServerErrorCode = -2130246396
ServerErrorTypeName = Microsoft.SharePoint.SPException
ServerErrorTraceCorrelationId = d9060c9f-00ff-1000-aec8-00494b05398a
TargetSite = Void ApplyRemoteTemplate(Microsoft.SharePoint.Client.Web, OfficeDevPnP.Core.Framework.Provisioning.Model.ProvisioningTemplate, OfficeDevPnP.Core.Framework.Provisioning.ObjectHandlers.ProvisioningTemplateApplyingInformation, Boolean, OfficeDevPnP.Core.Framework.Provisioning.ObjectHandlers.TokenParser)
StackTrace =    at OfficeDevPnP.Core.Framework.Provisioning.ObjectHandlers.SiteToTemplateConversion.ApplyRemoteTemplate(Web web, ProvisioningTemplate template, ProvisioningTemplateApplyingInformation provisioningInfo, Boolean calledFromHierarchy, TokenParser tokenParser) in c:\pnpbuild\Core\OfficeDevPnP.Core\Framework\Provisioning\ObjectHandlers\SiteToTemplateConversion.cs:line 441
   at Microsoft.SharePoint.Client.WebExtensions.ApplyProvisioningTemplate(Web web, ProvisioningTemplate template, ProvisioningTemplateApplyingInformation applyingInformation) in c:\pnpbuild\Core\OfficeDevPnP.Core\Extensions\WebExtensions.cs:line 1174
   at OfficeDevPnP.Core.Tests.Framework.Functional.Implementation.ImplementationBase.TestProvisioningTemplate(ClientContext cc, String templateName, Handlers handlersToProcess, ProvisioningTemplateApplyingInformation ptai, ProvisioningTemplateCreationInformation ptci) in c:\pnpbuild\Core\OfficeDevPnP.Core.Tests\Framework\Functional\Implementation\ImplementationBase.cs:line 42
HResult = -2146233088



Debug Trace:
vstest.console.exe Information: 0 : 2019-10-09 02:02:17.3060	[OfficeDevPnP.Core]	[0]	[Information]	File propertybag_add.xml retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-10-09 02:02:17.3997	[Provisioning]	[29]	[Debug]	Code execution scope started	0ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:17.3997	[Provisioning]	[29]	[Information]	ProgressDelegate registered	0ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:17.4778	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:17.4778	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:17.4778	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:17.4778	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:17.4778	[Property bag entries]	[29]	[Debug]	Code execution scope started	0ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:17.6185	[Property bag entries]	[29]	[Debug]	Overwriting existing propertybag entry PROP_1 with value https://testautomation.sharepointpnp.com	136ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:17.8529	[Property bag entries]	[29]	[Debug]	Creating new propertybag entry PROP_2 with value https://localhost:44300	372ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:18.0560	[Property bag entries]	[29]	[Debug]	Creating new propertybag entry PROP_3 with value https://localhost:4300,Indexed = true	582ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:18.4271	[Property bag entries]	[29]	[Debug]	Code execution scope ended	954ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:18.4271	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:18.4427	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:18.4427	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:18.4427	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:18.4427	[Persist Template Info]	[29]	[Debug]	Code execution scope started	0ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:19.0118	[Persist Template Info]	[29]	[Debug]	Code execution scope ended	577ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:19.0118	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:19.0118	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:19.0743	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:19.0899	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:19.0899	[Provisioning]	[29]	[Debug]	Code execution scope ended	1682ms	e87ab454-4d63-494d-a1a2-cf0f2e5ec3a0
vstest.console.exe Information: 0 : 2019-10-09 02:02:19.3864	[Extraction]	[29]	[Debug]	Code execution scope started	0ms	d13d0ac4-1bf9-4f51-be95-b13a3da4aeda
vstest.console.exe Information: 0 : 2019-10-09 02:02:19.3864	[Extraction]	[29]	[Debug]	Base template available: STS3template	0ms	d13d0ac4-1bf9-4f51-be95-b13a3da4aeda
vstest.console.exe Information: 0 : 2019-10-09 02:02:19.3864	[Extraction]	[29]	[Debug]	ProgressDelegate registered	0ms	d13d0ac4-1bf9-4f51-be95-b13a3da4aeda
vstest.console.exe Information: 0 : 2019-10-09 02:02:19.3864	[Property bag entries]	[29]	[Debug]	Code execution scope started	0ms	d13d0ac4-1bf9-4f51-be95-b13a3da4aeda
vstest.console.exe Information: 0 : 2019-10-09 02:02:19.6677	[Property bag entries]	[29]	[Debug]	Code execution scope ended	286ms	d13d0ac4-1bf9-4f51-be95-b13a3da4aeda
vstest.console.exe Information: 0 : 2019-10-09 02:02:19.6677	[Retrieve Template Info]	[29]	[Debug]	Code execution scope started	0ms	d13d0ac4-1bf9-4f51-be95-b13a3da4aeda
vstest.console.exe Information: 0 : 2019-10-09 02:02:19.9375	[Retrieve Template Info]	[29]	[Debug]	Code execution scope ended	274ms	d13d0ac4-1bf9-4f51-be95-b13a3da4aeda
vstest.console.exe Information: 0 : 2019-10-09 02:02:19.9375	[Extraction]	[29]	[Debug]	Code execution scope ended	563ms	d13d0ac4-1bf9-4f51-be95-b13a3da4aeda
vstest.console.exe Information: 0 : 2019-10-09 02:02:19.9690	[OfficeDevPnP.Core]	[0]	[Information]	File propertybag_delta_1.xml retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-10-09 02:02:19.9844	[Provisioning]	[29]	[Debug]	Code execution scope started	0ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:19.9844	[Provisioning]	[29]	[Information]	ProgressDelegate registered	0ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:20.0001	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:20.0001	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:20.0001	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:20.0001	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:20.0001	[Property bag entries]	[29]	[Debug]	Code execution scope started	0ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:20.1407	[Property bag entries]	[29]	[Debug]	Overwriting existing propertybag entry PROP_1 with value Hi {sitename}	130ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:20.4719	[Property bag entries]	[29]	[Debug]	Code execution scope ended	471ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:20.4719	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:20.4719	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:20.4719	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:20.4719	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:20.4719	[Persist Template Info]	[29]	[Debug]	Code execution scope started	0ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:20.9212	[Persist Template Info]	[29]	[Debug]	Code execution scope ended	450ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:20.9212	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:20.9212	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:20.9212	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:20.9212	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:20.9212	[Provisioning]	[29]	[Debug]	Code execution scope ended	938ms	5b5fbaf2-0365-4dee-a360-2736a68a75f3
vstest.console.exe Information: 0 : 2019-10-09 02:02:21.0775	[Extraction]	[29]	[Debug]	Code execution scope started	0ms	85dfeb65-ae7b-4ddd-881f-badf86391e5d
vstest.console.exe Information: 0 : 2019-10-09 02:02:21.0775	[Extraction]	[29]	[Debug]	Base template available: STS3template	0ms	85dfeb65-ae7b-4ddd-881f-badf86391e5d
vstest.console.exe Information: 0 : 2019-10-09 02:02:21.0775	[Extraction]	[29]	[Debug]	ProgressDelegate registered	0ms	85dfeb65-ae7b-4ddd-881f-badf86391e5d
vstest.console.exe Information: 0 : 2019-10-09 02:02:21.0775	[Property bag entries]	[29]	[Debug]	Code execution scope started	0ms	85dfeb65-ae7b-4ddd-881f-badf86391e5d
vstest.console.exe Information: 0 : 2019-10-09 02:02:21.3900	[Property bag entries]	[29]	[Debug]	Code execution scope ended	304ms	85dfeb65-ae7b-4ddd-881f-badf86391e5d
vstest.console.exe Information: 0 : 2019-10-09 02:02:21.3900	[Retrieve Template Info]	[29]	[Debug]	Code execution scope started	0ms	85dfeb65-ae7b-4ddd-881f-badf86391e5d
vstest.console.exe Information: 0 : 2019-10-09 02:02:21.6833	[Retrieve Template Info]	[29]	[Debug]	Code execution scope ended	294ms	85dfeb65-ae7b-4ddd-881f-badf86391e5d
vstest.console.exe Information: 0 : 2019-10-09 02:02:21.6833	[Extraction]	[29]	[Debug]	Code execution scope ended	600ms	85dfeb65-ae7b-4ddd-881f-badf86391e5d
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.0130	[OfficeDevPnP.Core]	[0]	[Information]	File propertybag_delta_2.xml retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.0443	[Provisioning]	[29]	[Debug]	Code execution scope started	0ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.0443	[Provisioning]	[29]	[Information]	ProgressDelegate registered	0ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.0599	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.0599	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.0599	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.0599	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.0599	[Property bag entries]	[29]	[Debug]	Code execution scope started	0ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.2630	[Property bag entries]	[29]	[Debug]	Code execution scope ended	198ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.2630	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.2630	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.2630	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.2630	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.2630	[Persist Template Info]	[29]	[Debug]	Code execution scope started	0ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.6973	[Persist Template Info]	[29]	[Debug]	Code execution scope ended	441ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.6973	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.6973	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.6973	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.6973	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.6973	[Provisioning]	[29]	[Debug]	Code execution scope ended	659ms	df2264fe-a561-4ec3-94f7-cb4d8b98d769
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.6973	[Extraction]	[29]	[Debug]	Code execution scope started	0ms	cf5bb620-cd35-4f76-a6a0-f12b7aa249c2
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.6973	[Extraction]	[29]	[Debug]	ProgressDelegate registered	0ms	cf5bb620-cd35-4f76-a6a0-f12b7aa249c2
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.7129	[Property bag entries]	[29]	[Debug]	Code execution scope started	0ms	cf5bb620-cd35-4f76-a6a0-f12b7aa249c2
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.9980	[Property bag entries]	[29]	[Debug]	Code execution scope ended	289ms	cf5bb620-cd35-4f76-a6a0-f12b7aa249c2
vstest.console.exe Information: 0 : 2019-10-09 02:02:22.9980	[Retrieve Template Info]	[29]	[Debug]	Code execution scope started	0ms	cf5bb620-cd35-4f76-a6a0-f12b7aa249c2
vstest.console.exe Information: 0 : 2019-10-09 02:02:23.4478	[Retrieve Template Info]	[29]	[Debug]	Code execution scope ended	456ms	cf5bb620-cd35-4f76-a6a0-f12b7aa249c2
vstest.console.exe Information: 0 : 2019-10-09 02:02:23.4478	[Extraction]	[29]	[Debug]	Code execution scope ended	747ms	cf5bb620-cd35-4f76-a6a0-f12b7aa249c2
vstest.console.exe Information: 0 : 2019-10-09 02:02:23.7427	[OfficeDevPnP.Core]	[0]	[Information]	File propertybag_delta_2.xml retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-10-09 02:02:23.7741	[Provisioning]	[29]	[Debug]	Code execution scope started	0ms	79a7f0a2-8a8c-4b04-8b23-537f15fc81a1
vstest.console.exe Information: 0 : 2019-10-09 02:02:23.7741	[Provisioning]	[29]	[Information]	OverwriteSystemPropertyBagValues is to true	0ms	79a7f0a2-8a8c-4b04-8b23-537f15fc81a1
vstest.console.exe Information: 0 : 2019-10-09 02:02:23.7741	[Provisioning]	[29]	[Information]	ProgressDelegate registered	0ms	79a7f0a2-8a8c-4b04-8b23-537f15fc81a1
vstest.console.exe Information: 0 : 2019-10-09 02:02:23.7896	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	79a7f0a2-8a8c-4b04-8b23-537f15fc81a1
vstest.console.exe Information: 0 : 2019-10-09 02:02:23.7896	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	79a7f0a2-8a8c-4b04-8b23-537f15fc81a1
vstest.console.exe Information: 0 : 2019-10-09 02:02:23.7896	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	79a7f0a2-8a8c-4b04-8b23-537f15fc81a1
vstest.console.exe Information: 0 : 2019-10-09 02:02:23.7896	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	79a7f0a2-8a8c-4b04-8b23-537f15fc81a1
vstest.console.exe Information: 0 : 2019-10-09 02:02:23.7896	[Property bag entries]	[29]	[Debug]	Code execution scope started	0ms	79a7f0a2-8a8c-4b04-8b23-537f15fc81a1
vstest.console.exe Information: 0 : 2019-10-09 02:02:23.9302	[Property bag entries]	[29]	[Debug]	Overwriting existing propertybag entry vti_defaultlanguage with value nl-nl	130ms	79a7f0a2-8a8c-4b04-8b23-537f15fc81a1
vstest.console.exe Information: 0 : 2019-10-09 02:02:28.1484	[Property bag entries]	[29]	[Debug]	Code execution scope ended	4359ms	79a7f0a2-8a8c-4b04-8b23-537f15fc81a1
vstest.console.exe Information: 0 : 2019-10-09 02:02:28.1484	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope started	0ms	79a7f0a2-8a8c-4b04-8b23-537f15fc81a1
vstest.console.exe Information: 0 : 2019-10-09 02:02:28.1484	[ProvisioningTemplate WebHook Call]	[29]	[Debug]	Code execution scope ended	0ms	79a7f0a2-8a8c-4b04-8b23-537f15fc81a1
vstest.console.exe Information: 0 : 2019-10-09 02:02:28.1484	[Provisioning]	[29]	[Debug]	Code execution scope ended	4378ms	79a7f0a2-8a8c-4b04-8b23-537f15fc81a1