Test case details
Test name:
OfficeDevPnP.Core.Tests.Framework.Functional.WebSettingsTests.SiteCollectionWebSettingsTest
Outcome:
Run at:
5/14/2019 7:55:12 PM
Duration:
00:00:02
Error message:
Assert.IsTrue failed.
Stacktrace:
at at OfficeDevPnP.Core.Tests.Framework.Functional.Implementation.WebSettingsImplementation.SiteCollectionWebSettings(String url) in c:\pnpbuild\Core\OfficeDevPnP.Core.Tests\Framework\Functional\Implementation\WebSettingsImplementation.cs:line 18 at OfficeDevPnP.Core.Tests.Framework.Functional.WebSettingsTests.SiteCollectionWebSettingsTest() in c:\pnpbuild\Core\OfficeDevPnP.Core.Tests\Framework\Functional\WebSettingsTests.cs:line 54
Console output:
    Applying template - 1/2 - Initializing engine
Applying template - 2/2 - Files
Applying template - 1/2 - Initializing engine
Applying template - 2/2 - Web Settings
Getting template - 1/1 - Web Settings
Source XML:<pnp:WebSettings RequestAccessEmail="test@pnp.com" WelcomePage="SitePages/Home.aspx" SiteLogo="{sitecollection}/SiteAssets/garagelogo.png" AlternateCSS="{sitecollection}/SiteAssets/garage.css" xmlns:pnp="http://schemas.dev.office.com/PnP/2015/12/ProvisioningSchema" />
Target XML:<pnp:WebSettings RequestAccessEmail="test@pnp.com" WelcomePage="SitePages/Home.aspx" SiteLogo="/sites/TestPnPSC_12345_eb3b6303-838b-4b9d-a928-252abfb3f314/SiteAssets/garagelogo.png" AlternateCSS="/sites/TestPnPSC_12345_eb3b6303-838b-4b9d-a928-252abfb3f314/SiteAssets/garage.css" xmlns:pnp="http://schemas.dev.office.com/PnP/2015/12/ProvisioningSchema" />



Debug Trace:
vstest.console.exe Information: 0 : 2019-05-14 19:55:12.9105	[OfficeDevPnP.Core]	[0]	[Debug]	AuthenticationManager:EnsureToken(siteUrl:https://portal2016.pnp.com/sites/tenantadmin,realm:d8623c9e-30c7-473a-83bc-d907df44a26e,appId:9ff790d6-1175-4abc-9891-8ed32d12b06a,appSecret:PRIVATE)	0ms	
vstest.console.exe Information: 0 : 2019-05-14 19:55:13.0512	[OfficeDevPnP.Core]	[0]	[Debug]	Site search ''	0ms	
vstest.console.exe Information: 0 : 2019-05-14 19:55:13.0512	[OfficeDevPnP.Core]	[0]	[Debug]	Lease expiration date: 5/15/2019 3:55:12 AM	0ms	
vstest.console.exe Error: 0 : 2019-05-14 19:55:13.1292	[OfficeDevPnP.Core]	[0]	[Error]	Site search error. Error = Search has encountered a problem that prevents results from being returned.  If the issue persists, please contact your administrator.	0ms	
vstest.console.exe Information: 0 : 2019-05-14 19:55:13.1292	[OfficeDevPnP.Core]	[0]	[Debug]	AuthenticationManager:EnsureToken(siteUrl:https://portal2016.pnp.com/sites/pnptest,realm:d8623c9e-30c7-473a-83bc-d907df44a26e,appId:9ff790d6-1175-4abc-9891-8ed32d12b06a,appSecret:PRIVATE)	0ms	
vstest.console.exe Information: 0 : 2019-05-14 19:55:13.6762	[OfficeDevPnP.Core]	[0]	[Debug]	Lease expiration date: 5/15/2019 3:55:13 AM	0ms	
vstest.console.exe Information: 0 : 2019-05-14 19:55:55.6918	[OfficeDevPnP.Core]	[0]	[Information]	Creating web 'sub' with template ''.	0ms	
vstest.console.exe Information: 0 : 2019-05-14 19:56:00.8792	[OfficeDevPnP.Core]	[0]	[Debug]	AuthenticationManager:EnsureToken(siteUrl:https://portal2016.pnp.com/sites/TestPnPSC_12345_eb3b6303-838b-4b9d-a928-252abfb3f314,realm:d8623c9e-30c7-473a-83bc-d907df44a26e,appId:9ff790d6-1175-4abc-9891-8ed32d12b06a,appSecret:PRIVATE)	0ms	
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.0198	[OfficeDevPnP.Core]	[0]	[Information]	File websettings_files.xml retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.0198	[OfficeDevPnP.Core]	[0]	[Debug]	Lease expiration date: 5/15/2019 3:56:00 AM	0ms	
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.1448	[Provisioning]	[480]	[Debug]	Code execution scope started	0ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.1448	[Provisioning]	[480]	[Information]	ProgressDelegate registered	0ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.1760	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope started	0ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.1760	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope ended	0ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.1760	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope started	0ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.1760	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope ended	0ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.1760	[Files]	[480]	[Debug]	Code execution scope started	0ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.3011	[OfficeDevPnP.Core]	[0]	[Information]	File garagelogo.png retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.3011	[Files]	[480]	[Debug]	Uploading file garagelogo.png	123ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.3011	[OfficeDevPnP.Core]	[0]	[Debug]	Creating file info with Url 'garagelogo.png'	0ms	
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.6178	[OfficeDevPnP.Core]	[0]	[Information]	File garage.css retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.6334	[Files]	[480]	[Debug]	Uploading file garage.css	445ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.6334	[OfficeDevPnP.Core]	[0]	[Debug]	Creating file info with Url 'garage.css'	0ms	
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.8521	[Files]	[480]	[Debug]	Code execution scope ended	667ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.8521	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope started	0ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.8521	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope ended	0ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.8521	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope started	0ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.8521	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope ended	0ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.8521	[Persist Template Info]	[480]	[Debug]	Code execution scope started	0ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.9928	[Persist Template Info]	[480]	[Debug]	Code execution scope ended	145ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.9928	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope started	0ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:01.9928	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope ended	0ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.0084	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope started	0ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.0084	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope ended	0ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.0084	[Provisioning]	[480]	[Debug]	Code execution scope ended	868ms	1cf655ca-5c31-4bf3-b108-f2666f2f286e
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.2740	[Extraction]	[480]	[Debug]	Code execution scope started	0ms	945dc731-13e3-4c92-aa53-61ff8534b8e4
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.2897	[Extraction]	[480]	[Debug]	Base template available: STS0template	0ms	945dc731-13e3-4c92-aa53-61ff8534b8e4
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.2897	[Extraction]	[480]	[Debug]	ProgressDelegate registered	0ms	945dc731-13e3-4c92-aa53-61ff8534b8e4
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.2897	[Retrieve Template Info]	[480]	[Debug]	Code execution scope started	0ms	945dc731-13e3-4c92-aa53-61ff8534b8e4
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.3521	[Retrieve Template Info]	[480]	[Debug]	Code execution scope ended	69ms	945dc731-13e3-4c92-aa53-61ff8534b8e4
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.3521	[Extraction]	[480]	[Debug]	Code execution scope ended	70ms	945dc731-13e3-4c92-aa53-61ff8534b8e4
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.3678	[OfficeDevPnP.Core]	[0]	[Information]	File websettings_add.xml retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.3834	[Provisioning]	[480]	[Debug]	Code execution scope started	0ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.3834	[Provisioning]	[480]	[Information]	ProgressDelegate registered	0ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.3990	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope started	0ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.3990	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope ended	0ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.3990	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope started	0ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.3990	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope ended	0ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.3990	[Web Settings]	[480]	[Debug]	Code execution scope started	0ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.6491	[Web Settings]	[480]	[Debug]	Code execution scope ended	239ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.6491	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope started	0ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.6491	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope ended	0ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.6491	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope started	0ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.6491	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope ended	0ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.6491	[Persist Template Info]	[480]	[Debug]	Code execution scope started	0ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.7584	[Persist Template Info]	[480]	[Debug]	Code execution scope ended	118ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.7584	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope started	0ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.7584	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope ended	0ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.7584	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope started	0ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.7584	[ProvisioningTemplate WebHook Call]	[480]	[Debug]	Code execution scope ended	0ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:02.7584	[Provisioning]	[480]	[Debug]	Code execution scope ended	374ms	6654d6de-b251-440b-ac9e-cd202cad2f2d
vstest.console.exe Information: 0 : 2019-05-14 19:56:03.0240	[Extraction]	[480]	[Debug]	Code execution scope started	0ms	7e42aeb8-d5f6-4c41-ad78-f781cf4986ba
vstest.console.exe Information: 0 : 2019-05-14 19:56:03.0240	[Extraction]	[480]	[Debug]	Base template available: STS0template	0ms	7e42aeb8-d5f6-4c41-ad78-f781cf4986ba
vstest.console.exe Information: 0 : 2019-05-14 19:56:03.0240	[Extraction]	[480]	[Debug]	ProgressDelegate registered	0ms	7e42aeb8-d5f6-4c41-ad78-f781cf4986ba
vstest.console.exe Information: 0 : 2019-05-14 19:56:03.0240	[Web Settings]	[480]	[Debug]	Code execution scope started	0ms	7e42aeb8-d5f6-4c41-ad78-f781cf4986ba
vstest.console.exe Information: 0 : 2019-05-14 19:56:03.0709	[Web Settings]	[480]	[Debug]	Code execution scope ended	41ms	7e42aeb8-d5f6-4c41-ad78-f781cf4986ba
vstest.console.exe Information: 0 : 2019-05-14 19:56:03.0709	[Retrieve Template Info]	[480]	[Debug]	Code execution scope started	0ms	7e42aeb8-d5f6-4c41-ad78-f781cf4986ba
vstest.console.exe Information: 0 : 2019-05-14 19:56:03.1178	[Retrieve Template Info]	[480]	[Debug]	Code execution scope ended	61ms	7e42aeb8-d5f6-4c41-ad78-f781cf4986ba
vstest.console.exe Information: 0 : 2019-05-14 19:56:03.1178	[Extraction]	[480]	[Debug]	Code execution scope ended	103ms	7e42aeb8-d5f6-4c41-ad78-f781cf4986ba