Test case details
Test name:
OfficeDevPnP.Core.Tests.Framework.Functional.ContentTypeTests.SiteCollectionContentTypeAddingTest
Outcome:
Run at:
5/16/2019 8:18:21 PM
Duration:
00:00:28
Error message:
Assert.IsTrue failed.
Stacktrace:
at at OfficeDevPnP.Core.Tests.Framework.Functional.Implementation.ContentTypeImplementation.SiteCollectionContentTypeAdding(String url) in c:\pnpbuild\Core\OfficeDevPnP.Core.Tests\Framework\Functional\Implementation\ContentTypeImplementation.cs:line 25 at OfficeDevPnP.Core.Tests.Framework.Functional.ContentTypeTests.SiteCollectionContentTypeAddingTest() in c:\pnpbuild\Core\OfficeDevPnP.Core.Tests\Framework\Functional\ContentTypeTests.cs:line 42
Console output:
    Applying template - 1/2 - Initializing engine
Applying template - 2/2 - Files
Applying template - 1/5 - Initializing engine
Applying template - 2/5 - Fields (ListAndStandardFields)
Applying template - 3/5 - Content Types (ListAndStandardFields)
Applying template - 4/5 - Fields (LookupFields)
Applying template - 5/5 - Content Types (LookupFields)
Getting template - 1/2 - Fields (Export)
Getting template - 2/2 - Content Types (Export)
Source XML:<pnp:ContentType ID="0x0101005D4F34E4BE7F4B6892AEBE088EDD215E" Name="CT_1" Description="CT 1" Group="PnP Demo" xmlns:pnp="http://schemas.dev.office.com/PnP/2015/12/ProvisioningSchema">
  <pnp:FieldRefs>
    <pnp:FieldRef ID="92bc6a41-d501-4fe2-938e-17fbf574a7cb" />
  </pnp:FieldRefs>
  <pnp:DocumentTemplate TargetName="{sitecollection}/siteassets/ct1document.docx" />
</pnp:ContentType>
Target XML:<pnp:ContentType ID="0x0101005D4F34E4BE7F4B6892AEBE088EDD215E" Name="CT_1" Description="CT 1" Group="PnP Demo" xmlns:pnp="http://schemas.dev.office.com/PnP/2015/12/ProvisioningSchema">
  <pnp:FieldRefs>
    <pnp:FieldRef ID="92bc6a41-d501-4fe2-938e-17fbf574a7cb" />
  </pnp:FieldRefs>
  <pnp:DocumentTemplate TargetName="/sites/TestPnPSC_12345_2db57b33-7617-4c4c-83fc-b55fd88665aa/siteassets/ct1document.docx" />
</pnp:ContentType>
-- Content type validation False



Debug Trace:
vstest.console.exe Information: 0 : 2019-05-16 20:18:23.6269	[SetSiteLockState: Current: {0} Target: {1}]	[0]	[Information]	Unlock	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:22.1600	[OfficeDevPnP.Core]	[0]	[Information]	Creating web 'sub' with template ''.	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:28.7073	[OfficeDevPnP.Core]	[0]	[Information]	File contenttype_files.xml retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:28.8670	[Provisioning]	[12]	[Debug]	Code execution scope started	0ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:28.8670	[Provisioning]	[12]	[Information]	ProgressDelegate registered	0ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:29.0077	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:29.0077	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:29.0077	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:29.0077	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:29.0077	[Files]	[12]	[Debug]	Code execution scope started	0ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:29.3046	[OfficeDevPnP.Core]	[0]	[Information]	File ct1document.docx retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:29.3046	[Files]	[12]	[Debug]	Uploading file ct1document.docx	291ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:29.3046	[OfficeDevPnP.Core]	[0]	[Debug]	Creating file info with Url 'ct1document.docx'	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:29.9300	[OfficeDevPnP.Core]	[0]	[Information]	File ct3document.docx retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:29.9300	[Files]	[12]	[Debug]	Uploading file ct3document.docx	915ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:29.9300	[OfficeDevPnP.Core]	[0]	[Debug]	Creating file info with Url 'ct3document.docx'	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:30.6513	[OfficeDevPnP.Core]	[0]	[Information]	File CT3DisplayForm.aspx retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:30.6669	[Files]	[12]	[Debug]	Uploading file CT3DisplayForm.aspx	1649ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:30.6669	[OfficeDevPnP.Core]	[0]	[Debug]	Creating file info with Url 'CT3DisplayForm.aspx'	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:31.3456	[OfficeDevPnP.Core]	[0]	[Information]	File CT3EditForm.aspx retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:31.3456	[Files]	[12]	[Debug]	Uploading file CT3EditForm.aspx	2332ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:31.3456	[OfficeDevPnP.Core]	[0]	[Debug]	Creating file info with Url 'CT3EditForm.aspx'	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:31.8455	[Files]	[12]	[Debug]	Code execution scope ended	2838ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:31.8455	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:31.8455	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:31.8455	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:31.8455	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:31.8455	[Persist Template Info]	[12]	[Debug]	Code execution scope started	0ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:32.3556	[Persist Template Info]	[12]	[Debug]	Code execution scope ended	498ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:32.3556	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:32.3556	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:32.4231	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:32.4231	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:32.4231	[Provisioning]	[12]	[Debug]	Code execution scope ended	3545ms	834594d8-dc13-4f54-bb8c-85d8bc4fa643
vstest.console.exe Information: 0 : 2019-05-16 20:21:32.6502	[Extraction]	[12]	[Debug]	Code execution scope started	0ms	77069915-e16f-4613-8c47-bb84ab07d3a0
vstest.console.exe Information: 0 : 2019-05-16 20:21:32.6502	[Extraction]	[12]	[Debug]	Base template available: STS0template	0ms	77069915-e16f-4613-8c47-bb84ab07d3a0
vstest.console.exe Information: 0 : 2019-05-16 20:21:32.6502	[Extraction]	[12]	[Debug]	ProgressDelegate registered	0ms	77069915-e16f-4613-8c47-bb84ab07d3a0
vstest.console.exe Information: 0 : 2019-05-16 20:21:32.6502	[Retrieve Template Info]	[12]	[Debug]	Code execution scope started	0ms	77069915-e16f-4613-8c47-bb84ab07d3a0
vstest.console.exe Information: 0 : 2019-05-16 20:21:32.8846	[Retrieve Template Info]	[12]	[Debug]	Code execution scope ended	247ms	77069915-e16f-4613-8c47-bb84ab07d3a0
vstest.console.exe Information: 0 : 2019-05-16 20:21:32.8846	[Extraction]	[12]	[Debug]	Code execution scope ended	248ms	77069915-e16f-4613-8c47-bb84ab07d3a0
vstest.console.exe Information: 0 : 2019-05-16 20:21:33.2128	[OfficeDevPnP.Core]	[0]	[Information]	File contenttype_add.xml retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:33.2600	[Provisioning]	[12]	[Debug]	Code execution scope started	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:33.2600	[Provisioning]	[12]	[Information]	ProgressDelegate registered	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:33.2909	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:33.2909	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:33.2909	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:33.2909	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:33.2909	[Fields (ListAndStandardFields)]	[12]	[Debug]	Code execution scope started	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:33.6347	[Fields (ListAndStandardFields)]	[12]	[Debug]	Adding field {92BC6A41-D501-4FE2-938E-17FBF574A7CB} to site	344ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:33.8784	[Fields (ListAndStandardFields)]	[12]	[Debug]	Adding field {56407FDD-C7FA-4321-8FA5-06B7AF970519} to site	580ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:34.0462	[Fields (ListAndStandardFields)]	[12]	[Debug]	Adding field {B46D91E2-C755-4C3A-AC72-7FD3E4853476} to site	750ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:34.2025	[Fields (ListAndStandardFields)]	[12]	[Debug]	Adding field {B2C51364-8C48-4779-8265-1169B81CE5A0} to site	911ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:34.3921	[Fields (ListAndStandardFields)]	[12]	[Debug]	Code execution scope ended	1095ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:34.3921	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:34.3921	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:34.3921	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:34.3921	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:34.3921	[Content Types (ListAndStandardFields)]	[12]	[Debug]	Code execution scope started	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:36.5463	[Content Types (ListAndStandardFields)]	[12]	[Debug]	Creating new Content Type: 0x010002256D58F4CF427DA2AAA876B1DCF366 - CT_12	2153ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:36.5463	[OfficeDevPnP.Core]	[0]	[Information]	Creating content type 'CT_12' (0x010002256D58F4CF427DA2AAA876B1DCF366).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:37.2102	[OfficeDevPnP.Core]	[0]	[Information]	Adding field (92bc6a41-d501-4fe2-938e-17fbf574a7cb) to content type (0x010002256D58F4CF427DA2AAA876B1DCF366).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:37.9159	[OfficeDevPnP.Core]	[0]	[Information]	Adding field (56407fdd-c7fa-4321-8fa5-06b7af970519) to content type (0x010002256D58F4CF427DA2AAA876B1DCF366).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:38.4065	[OfficeDevPnP.Core]	[0]	[Information]	Adding field (b46d91e2-c755-4c3a-ac72-7fd3e4853476) to content type (0x010002256D58F4CF427DA2AAA876B1DCF366).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:38.9222	[OfficeDevPnP.Core]	[0]	[Information]	Adding field (b2c51364-8c48-4779-8265-1169b81ce5a0) to content type (0x010002256D58F4CF427DA2AAA876B1DCF366).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:39.3888	[Content Types (ListAndStandardFields)]	[12]	[Debug]	Creating new Content Type: 0x010076029D9059824413B137EA0A9B5FAAA0 - CT_10	4991ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:39.3941	[OfficeDevPnP.Core]	[0]	[Information]	Creating content type 'CT_10' (0x010076029D9059824413B137EA0A9B5FAAA0).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:39.9427	[OfficeDevPnP.Core]	[0]	[Information]	Adding field (92bc6a41-d501-4fe2-938e-17fbf574a7cb) to content type (0x010076029D9059824413B137EA0A9B5FAAA0).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:40.3802	[Content Types (ListAndStandardFields)]	[12]	[Debug]	Creating new Content Type: 0x0100B1459203A48B4E878238B723CD10EECA - CT_11	5985ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:40.3802	[OfficeDevPnP.Core]	[0]	[Information]	Creating content type 'CT_11' (0x0100B1459203A48B4E878238B723CD10EECA).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:40.8607	[Content Types (ListAndStandardFields)]	[12]	[Debug]	Creating new Content Type: 0x0101001AF9DBB0E32F47A1A0BA519392153128 - CT_3	6461ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:40.8607	[OfficeDevPnP.Core]	[0]	[Information]	Creating content type 'CT_3' (0x0101001AF9DBB0E32F47A1A0BA519392153128).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:41.4511	[OfficeDevPnP.Core]	[0]	[Information]	Adding field (92bc6a41-d501-4fe2-938e-17fbf574a7cb) to content type (0x0101001AF9DBB0E32F47A1A0BA519392153128).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:42.0278	[OfficeDevPnP.Core]	[0]	[Information]	Adding field (56407fdd-c7fa-4321-8fa5-06b7af970519) to content type (0x0101001AF9DBB0E32F47A1A0BA519392153128).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:42.5488	[OfficeDevPnP.Core]	[0]	[Information]	Adding field (b46d91e2-c755-4c3a-ac72-7fd3e4853476) to content type (0x0101001AF9DBB0E32F47A1A0BA519392153128).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:43.0466	[OfficeDevPnP.Core]	[0]	[Information]	Adding field (b2c51364-8c48-4779-8265-1169b81ce5a0) to content type (0x0101001AF9DBB0E32F47A1A0BA519392153128).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:43.5096	[Content Types (ListAndStandardFields)]	[12]	[Debug]	Creating new Content Type: 0x0101005D4F34E4BE7F4B6892AEBE088EDD215E - CT_1	9110ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:43.5096	[OfficeDevPnP.Core]	[0]	[Information]	Creating content type 'CT_1' (0x0101005D4F34E4BE7F4B6892AEBE088EDD215E).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:44.0741	[OfficeDevPnP.Core]	[0]	[Information]	Adding field (92bc6a41-d501-4fe2-938e-17fbf574a7cb) to content type (0x0101005D4F34E4BE7F4B6892AEBE088EDD215E).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:44.4864	[Content Types (ListAndStandardFields)]	[12]	[Debug]	Creating new Content Type: 0x0101008C05EC4BEB1F4DFE9D8828D2DA449D41 - CT_2	10084ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:44.4864	[OfficeDevPnP.Core]	[0]	[Information]	Creating content type 'CT_2' (0x0101008C05EC4BEB1F4DFE9D8828D2DA449D41).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:44.9192	[Content Types (ListAndStandardFields)]	[12]	[Debug]	Creating new Content Type: 0x0120D5200039D83CD2C9BA4A4499AEE6BE3562E023 - CT_20	10521ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:44.9192	[OfficeDevPnP.Core]	[0]	[Information]	Creating content type 'CT_20' (0x0120D5200039D83CD2C9BA4A4499AEE6BE3562E023).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:45.7710	[OfficeDevPnP.Core]	[0]	[Information]	Adding field (92bc6a41-d501-4fe2-938e-17fbf574a7cb) to content type (0x0120D5200039D83CD2C9BA4A4499AEE6BE3562E023).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:46.3487	[OfficeDevPnP.Core]	[0]	[Information]	Adding field (56407fdd-c7fa-4321-8fa5-06b7af970519) to content type (0x0120D5200039D83CD2C9BA4A4499AEE6BE3562E023).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:46.9750	[OfficeDevPnP.Core]	[0]	[Information]	Adding field (b46d91e2-c755-4c3a-ac72-7fd3e4853476) to content type (0x0120D5200039D83CD2C9BA4A4499AEE6BE3562E023).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:47.6243	[OfficeDevPnP.Core]	[0]	[Information]	Adding field (b2c51364-8c48-4779-8265-1169b81ce5a0) to content type (0x0120D5200039D83CD2C9BA4A4499AEE6BE3562E023).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:48.2493	[OfficeDevPnP.Core]	[0]	[Information]	File ./CT20Document.docx retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:48.2654	[OfficeDevPnP.Core]	[0]	[Information]	File ./CT20Document0101.docx retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:48.9673	[Content Types (ListAndStandardFields)]	[12]	[Debug]	Creating new Content Type: 0x0120D520006F71536046A14A71A827ECCAE8D5B1AA - CT_22	14564ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:48.9673	[OfficeDevPnP.Core]	[0]	[Information]	Creating content type 'CT_22' (0x0120D520006F71536046A14A71A827ECCAE8D5B1AA).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:49.5578	[OfficeDevPnP.Core]	[0]	[Information]	Adding field (92bc6a41-d501-4fe2-938e-17fbf574a7cb) to content type (0x0120D520006F71536046A14A71A827ECCAE8D5B1AA).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:50.3066	[Content Types (ListAndStandardFields)]	[12]	[Debug]	Creating new Content Type: 0x0120D52000E5DBFFDBB86B454096672F072D473FC8 - CT_21	15908ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:50.3066	[OfficeDevPnP.Core]	[0]	[Information]	Creating content type 'CT_21' (0x0120D52000E5DBFFDBB86B454096672F072D473FC8).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:50.9785	[OfficeDevPnP.Core]	[0]	[Information]	Adding field (92bc6a41-d501-4fe2-938e-17fbf574a7cb) to content type (0x0120D52000E5DBFFDBB86B454096672F072D473FC8).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:51.4541	[OfficeDevPnP.Core]	[0]	[Information]	Adding field (56407fdd-c7fa-4321-8fa5-06b7af970519) to content type (0x0120D52000E5DBFFDBB86B454096672F072D473FC8).	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:51.9336	[OfficeDevPnP.Core]	[0]	[Information]	File ./CT21Document.docx retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:21:52.3867	[Content Types (ListAndStandardFields)]	[12]	[Debug]	Code execution scope ended	17989ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:52.3867	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:52.3867	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:52.3867	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:52.3867	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:52.3867	[Fields (LookupFields)]	[12]	[Debug]	Code execution scope started	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:52.5003	[Fields (LookupFields)]	[12]	[Debug]	Code execution scope ended	122ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:52.5003	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:52.5160	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:52.5160	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:52.5160	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:52.5160	[Content Types (LookupFields)]	[12]	[Debug]	Code execution scope started	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:53.5511	[Content Types (LookupFields)]	[12]	[Debug]	Updating existing Content Type: 0x010002256D58F4CF427DA2AAA876B1DCF366 - CT_12	1047ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:53.5662	[Content Types (LookupFields)]	[12]	[Debug]	Field 92bc6a41-d501-4fe2-938e-17fbf574a7cb exists in content type	1050ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:53.5662	[Content Types (LookupFields)]	[12]	[Debug]	Field 56407fdd-c7fa-4321-8fa5-06b7af970519 exists in content type	1051ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:53.5662	[Content Types (LookupFields)]	[12]	[Debug]	Field b46d91e2-c755-4c3a-ac72-7fd3e4853476 exists in content type	1053ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:53.5662	[Content Types (LookupFields)]	[12]	[Debug]	Field b2c51364-8c48-4779-8265-1169b81ce5a0 exists in content type	1054ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:53.6756	[Content Types (LookupFields)]	[12]	[Debug]	Updating existing Content Type: 0x010076029D9059824413B137EA0A9B5FAAA0 - CT_10	1168ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:53.6756	[Content Types (LookupFields)]	[12]	[Debug]	Field 92bc6a41-d501-4fe2-938e-17fbf574a7cb exists in content type	1170ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:53.7850	[Content Types (LookupFields)]	[12]	[Debug]	Updating existing Content Type: 0x0100B1459203A48B4E878238B723CD10EECA - CT_11	1268ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:53.7850	[Content Types (LookupFields)]	[12]	[Debug]	Updating existing Content Type: 0x0101001AF9DBB0E32F47A1A0BA519392153128 - CT_3	1269ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:53.7850	[Content Types (LookupFields)]	[12]	[Debug]	Field 92bc6a41-d501-4fe2-938e-17fbf574a7cb exists in content type	1270ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:53.7850	[Content Types (LookupFields)]	[12]	[Debug]	Field 56407fdd-c7fa-4321-8fa5-06b7af970519 exists in content type	1271ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:53.8006	[Content Types (LookupFields)]	[12]	[Debug]	Field b46d91e2-c755-4c3a-ac72-7fd3e4853476 exists in content type	1284ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:53.8006	[Content Types (LookupFields)]	[12]	[Debug]	Field b2c51364-8c48-4779-8265-1169b81ce5a0 exists in content type	1285ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:53.9100	[Content Types (LookupFields)]	[12]	[Debug]	Updating existing Content Type: 0x0101005D4F34E4BE7F4B6892AEBE088EDD215E - CT_1	1395ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:53.9100	[Content Types (LookupFields)]	[12]	[Debug]	Field 92bc6a41-d501-4fe2-938e-17fbf574a7cb exists in content type	1397ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.0037	[Content Types (LookupFields)]	[12]	[Debug]	Updating existing Content Type: 0x0101008C05EC4BEB1F4DFE9D8828D2DA449D41 - CT_2	1498ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.0037	[Content Types (LookupFields)]	[12]	[Debug]	Updating existing Content Type: 0x0120D5200039D83CD2C9BA4A4499AEE6BE3562E023 - CT_20	1499ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.0037	[Content Types (LookupFields)]	[12]	[Debug]	Field 92bc6a41-d501-4fe2-938e-17fbf574a7cb exists in content type	1500ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.0037	[Content Types (LookupFields)]	[12]	[Debug]	Field 56407fdd-c7fa-4321-8fa5-06b7af970519 exists in content type	1502ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.0193	[Content Types (LookupFields)]	[12]	[Debug]	Field b46d91e2-c755-4c3a-ac72-7fd3e4853476 exists in content type	1503ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.0193	[Content Types (LookupFields)]	[12]	[Debug]	Field b2c51364-8c48-4779-8265-1169b81ce5a0 exists in content type	1504ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Error: 0 : 2019-05-16 20:21:54.0193	[Content Types (LookupFields)]	[12]	[Error]	Content Type 0x0120D5200039D83CD2C9BA4A4499AEE6BE3562E023 with ID CT_20 cannot be transformed into a DocumentSet	1504ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.1287	[Content Types (LookupFields)]	[12]	[Debug]	Updating existing Content Type: 0x0120D520006F71536046A14A71A827ECCAE8D5B1AA - CT_22	1621ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.1287	[Content Types (LookupFields)]	[12]	[Debug]	Field 92bc6a41-d501-4fe2-938e-17fbf574a7cb exists in content type	1622ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Error: 0 : 2019-05-16 20:21:54.1287	[Content Types (LookupFields)]	[12]	[Error]	Content Type 0x0120D520006F71536046A14A71A827ECCAE8D5B1AA with ID CT_22 cannot be transformed into a DocumentSet	1623ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.2381	[Content Types (LookupFields)]	[12]	[Debug]	Updating existing Content Type: 0x0120D52000E5DBFFDBB86B454096672F072D473FC8 - CT_21	1726ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.2381	[Content Types (LookupFields)]	[12]	[Debug]	Field 92bc6a41-d501-4fe2-938e-17fbf574a7cb exists in content type	1727ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.2381	[Content Types (LookupFields)]	[12]	[Debug]	Field 56407fdd-c7fa-4321-8fa5-06b7af970519 exists in content type	1728ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Error: 0 : 2019-05-16 20:21:54.2381	[Content Types (LookupFields)]	[12]	[Error]	Content Type 0x0120D52000E5DBFFDBB86B454096672F072D473FC8 with ID CT_21 cannot be transformed into a DocumentSet	1728ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.3475	[Content Types (LookupFields)]	[12]	[Debug]	Code execution scope ended	1836ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.3475	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.3475	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.3475	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.3475	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.3475	[Persist Template Info]	[12]	[Debug]	Code execution scope started	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.7694	[Persist Template Info]	[12]	[Debug]	Code execution scope ended	421ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.7694	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.7694	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.7694	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.7694	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:54.7694	[Provisioning]	[12]	[Debug]	Code execution scope ended	21506ms	f251272c-75a8-4810-b2a7-e1a3c51b7dbb
vstest.console.exe Information: 0 : 2019-05-16 20:21:55.0037	[Extraction]	[12]	[Debug]	Code execution scope started	0ms	996ffd02-6aa4-4a79-8e81-0143d393bc9f
vstest.console.exe Information: 0 : 2019-05-16 20:21:55.0037	[Extraction]	[12]	[Debug]	Base template available: STS0template	0ms	996ffd02-6aa4-4a79-8e81-0143d393bc9f
vstest.console.exe Information: 0 : 2019-05-16 20:21:55.0037	[Extraction]	[12]	[Debug]	ProgressDelegate registered	0ms	996ffd02-6aa4-4a79-8e81-0143d393bc9f
vstest.console.exe Information: 0 : 2019-05-16 20:21:55.0037	[Fields (Export)]	[12]	[Debug]	Code execution scope started	0ms	996ffd02-6aa4-4a79-8e81-0143d393bc9f
vstest.console.exe Information: 0 : 2019-05-16 20:21:55.7455	[Fields (Export)]	[12]	[Debug]	Code execution scope ended	742ms	996ffd02-6aa4-4a79-8e81-0143d393bc9f
vstest.console.exe Information: 0 : 2019-05-16 20:21:55.7455	[Content Types (Export)]	[12]	[Debug]	Code execution scope started	0ms	996ffd02-6aa4-4a79-8e81-0143d393bc9f
vstest.console.exe Information: 0 : 2019-05-16 20:21:57.1745	[Content Types (Export)]	[12]	[Debug]	Adding content type to template: 0x010085EC78BE64F9478AAE3ED069093B9963004A37C4B20C06FF4E85D6F87A91A460F1 - Project sites policy	1418ms	996ffd02-6aa4-4a79-8e81-0143d393bc9f
vstest.console.exe Information: 0 : 2019-05-16 20:21:57.1745	[Content Types (Export)]	[12]	[Debug]	Code execution scope ended	1418ms	996ffd02-6aa4-4a79-8e81-0143d393bc9f
vstest.console.exe Information: 0 : 2019-05-16 20:21:57.1745	[Retrieve Template Info]	[12]	[Debug]	Code execution scope started	0ms	996ffd02-6aa4-4a79-8e81-0143d393bc9f
vstest.console.exe Information: 0 : 2019-05-16 20:21:57.4761	[Retrieve Template Info]	[12]	[Debug]	Code execution scope ended	297ms	996ffd02-6aa4-4a79-8e81-0143d393bc9f
vstest.console.exe Information: 0 : 2019-05-16 20:21:57.4761	[Extraction]	[12]	[Debug]	Code execution scope ended	2461ms	996ffd02-6aa4-4a79-8e81-0143d393bc9f