Test case details
Test name:
OfficeDevPnP.Core.Tests.Framework.Functional.CustomActionTests.SiteCollectionCustomActionAddingTest
Outcome:
Run at:
5/16/2019 8:13:09 PM
Duration:
00:00:03
Error message:
Assert.IsTrue failed.
Stacktrace:
at at OfficeDevPnP.Core.Tests.Framework.Functional.Implementation.CustomActionImplementation.SiteCollectionCustomActionAdding(String url) in c:\pnpbuild\Core\OfficeDevPnP.Core.Tests\Framework\Functional\Implementation\CustomActionImplementation.cs:line 20 at OfficeDevPnP.Core.Tests.Framework.Functional.CustomActionTests.SiteCollectionCustomActionAddingTest() in c:\pnpbuild\Core\OfficeDevPnP.Core.Tests\Framework\Functional\CustomActionTests.cs:line 41
Console output:
    Applying template - 1/2 - Initializing engine
Applying template - 2/2 - Custom Actions
Getting template - 1/1 - Custom Actions
Custom Action validation started...
CA_2 is not matching
CA_3 is not matching
CA_1 is not matching
CA_2 is not matching
CA_3 is not matching
CA_1 is not matching
Site Custom Actions validation False
Web Custom  Actions validation True



Debug Trace:
vstest.console.exe Information: 0 : 2019-05-16 20:13:12.5257	[SetSiteLockState: Current: {0} Target: {1}]	[0]	[Information]	Unlock	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:15:53.7390	[OfficeDevPnP.Core]	[0]	[Information]	Creating web 'sub' with template ''.	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:16:00.3171	[OfficeDevPnP.Core]	[0]	[Information]	File customaction_add.xml retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-05-16 20:16:00.4351	[Provisioning]	[12]	[Debug]	Code execution scope started	0ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:00.4351	[Provisioning]	[12]	[Information]	ProgressDelegate registered	0ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:00.6227	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:00.6227	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:00.6227	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:00.6227	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:00.6227	[Custom Actions]	[12]	[Debug]	Code execution scope started	0ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:01.1697	[Custom Actions]	[12]	[Debug]	Adding custom action 'CA_1' to scope Site	548ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:01.4197	[Custom Actions]	[12]	[Debug]	Adding custom action 'CA_2' to scope Site	795ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:01.8102	[Custom Actions]	[12]	[Debug]	Adding custom action 'CA_3' to scope Site	1187ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:02.0759	[Custom Actions]	[12]	[Debug]	Adding custom action 'CA_4' to scope Web	1456ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:02.3080	[Custom Actions]	[12]	[Debug]	Code execution scope ended	1689ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:02.3080	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:02.3080	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:02.3080	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:02.3080	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:02.3080	[Persist Template Info]	[12]	[Debug]	Code execution scope started	0ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:02.8861	[Persist Template Info]	[12]	[Debug]	Code execution scope ended	576ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:02.8861	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:02.8861	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:02.9486	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope started	0ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:02.9486	[ProvisioningTemplate WebHook Call]	[12]	[Debug]	Code execution scope ended	0ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:02.9486	[Provisioning]	[12]	[Debug]	Code execution scope ended	2523ms	87bde1d0-e0fa-4ae8-8920-19682e883426
vstest.console.exe Information: 0 : 2019-05-16 20:16:03.1986	[Extraction]	[12]	[Debug]	Code execution scope started	0ms	e953404a-245d-4422-8b6e-a6c9787a4ab8
vstest.console.exe Information: 0 : 2019-05-16 20:16:03.1986	[Extraction]	[12]	[Debug]	Base template available: STS0template	0ms	e953404a-245d-4422-8b6e-a6c9787a4ab8
vstest.console.exe Information: 0 : 2019-05-16 20:16:03.1986	[Extraction]	[12]	[Debug]	ProgressDelegate registered	0ms	e953404a-245d-4422-8b6e-a6c9787a4ab8
vstest.console.exe Information: 0 : 2019-05-16 20:16:03.3437	[Custom Actions]	[12]	[Debug]	Code execution scope started	0ms	e953404a-245d-4422-8b6e-a6c9787a4ab8
vstest.console.exe Information: 0 : 2019-05-16 20:16:03.7187	[Custom Actions]	[12]	[Debug]	Adding web scoped custom action 'CA_4' to template	370ms	e953404a-245d-4422-8b6e-a6c9787a4ab8
vstest.console.exe Information: 0 : 2019-05-16 20:16:03.7187	[Custom Actions]	[12]	[Debug]	Adding site scoped custom action 'CA_2' to template	371ms	e953404a-245d-4422-8b6e-a6c9787a4ab8
vstest.console.exe Information: 0 : 2019-05-16 20:16:03.7187	[Custom Actions]	[12]	[Debug]	Adding site scoped custom action 'CA_3' to template	371ms	e953404a-245d-4422-8b6e-a6c9787a4ab8
vstest.console.exe Information: 0 : 2019-05-16 20:16:03.7187	[Custom Actions]	[12]	[Debug]	Adding site scoped custom action 'CA_1' to template	371ms	e953404a-245d-4422-8b6e-a6c9787a4ab8
vstest.console.exe Information: 0 : 2019-05-16 20:16:03.7187	[Custom Actions]	[12]	[Debug]	Code execution scope ended	372ms	e953404a-245d-4422-8b6e-a6c9787a4ab8
vstest.console.exe Information: 0 : 2019-05-16 20:16:03.7187	[Retrieve Template Info]	[12]	[Debug]	Code execution scope started	0ms	e953404a-245d-4422-8b6e-a6c9787a4ab8
vstest.console.exe Information: 0 : 2019-05-16 20:16:04.0313	[Retrieve Template Info]	[12]	[Debug]	Code execution scope ended	308ms	e953404a-245d-4422-8b6e-a6c9787a4ab8
vstest.console.exe Information: 0 : 2019-05-16 20:16:04.0313	[Extraction]	[12]	[Debug]	Code execution scope ended	823ms	e953404a-245d-4422-8b6e-a6c9787a4ab8