Test case details
Test name:
OfficeDevPnP.Core.Tests.Framework.Functional.PublishingTest.SiteCollectionPublishingTest
Outcome:
Run at:
11/8/2019 9:22:06 AM
Duration:
00:05:12
Error message:
Test 'SiteCollectionPublishingTest' exceeded execution timeout period.
Stacktrace:
N/A
Console output:
    Type = Microsoft.SharePoint.Client.ServerException
TraceCorrelationId = f0c7159f-30dc-1000-1fce-75d760127857
Message = File Not Found.
Source = Microsoft.SharePoint.Client.Runtime
ServerStackTrace = 
ServerErrorCode = -2147024894
ServerErrorTypeName = System.IO.FileNotFoundException
ServerErrorTraceCorrelationId = f0c7159f-30dc-1000-1fce-75d760127857
TargetSite = Void ProcessResponseStream(System.IO.Stream)
StackTrace =    at Microsoft.SharePoint.Client.ClientRequest.ProcessResponseStream(Stream responseStream)
   at Microsoft.SharePoint.Client.ClientRequest.ProcessResponse()
   at Microsoft.SharePoint.Client.ClientRequest.<ExecuteQueryToServerAsync>d__6.MoveNext()
--- End of stack trace from previous location where exception was thrown ---
   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
   at Microsoft.SharePoint.Client.ClientRequest.<ExecuteQueryAsync>d__0.MoveNext()
--- End of stack trace from previous location where exception was thrown ---
   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
   at Microsoft.SharePoint.Client.ClientRuntimeContext.<ExecuteQueryAsync>d__0.MoveNext()
--- End of stack trace from previous location where exception was thrown ---
   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
   at Microsoft.SharePoint.Client.ClientContext.<ExecuteQueryAsync>d__4.MoveNext()
--- End of stack trace from previous location where exception was thrown ---
   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.GetResult()
   at Microsoft.SharePoint.Client.ClientContextExtensions.<ExecuteQueryImplementation>d__7.MoveNext() in c:\pnpbuild\Core\OfficeDevPnP.Core\Extensions\ClientContextExtensions.cs:line 156
--- End of stack trace from previous location where exception was thrown ---
   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.GetResult()
   at Microsoft.SharePoint.Client.ClientContextExtensions.ExecuteQueryRetry(ClientRuntimeContext clientContext, Int32 retryCount, Int32 delay, String userAgent) in c:\pnpbuild\Core\OfficeDevPnP.Core\Extensions\ClientContextExtensions.cs:line 91
   at Microsoft.SharePoint.Client.TenantExtensions.DeleteSiteCollection(Tenant tenant, String siteFullUrl, Boolean useRecycleBin, Func`2 timeoutFunction) in c:\pnpbuild\Core\OfficeDevPnP.Core\Extensions\TenantExtensions.cs:line 413
   at OfficeDevPnP.Core.Tests.Framework.Functional.FunctionalTestBase.CleanupAllTestSiteCollections(ClientContext tenantContext) in c:\pnpbuild\Core\OfficeDevPnP.Core.Tests\Framework\Functional\FunctionalTestBase.cs:line 155
HResult = -2146233088
Type = Microsoft.SharePoint.Client.ServerException
TraceCorrelationId = f1c7159f-100d-1000-1fce-7e1076a5dec8
Message = File Not Found.
Source = Microsoft.SharePoint.Client.Runtime
ServerStackTrace = 
ServerErrorCode = -2147024894
ServerErrorTypeName = System.IO.FileNotFoundException
ServerErrorTraceCorrelationId = f1c7159f-100d-1000-1fce-7e1076a5dec8
TargetSite = Void ProcessResponseStream(System.IO.Stream)
StackTrace =    at Microsoft.SharePoint.Client.ClientRequest.ProcessResponseStream(Stream responseStream)
   at Microsoft.SharePoint.Client.ClientRequest.ProcessResponse()
   at Microsoft.SharePoint.Client.ClientRequest.<ExecuteQueryToServerAsync>d__6.MoveNext()
--- End of stack trace from previous location where exception was thrown ---
   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
   at Microsoft.SharePoint.Client.ClientRequest.<ExecuteQueryAsync>d__0.MoveNext()
--- End of stack trace from previous location where exception was thrown ---
   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
   at Microsoft.SharePoint.Client.ClientRuntimeContext.<ExecuteQueryAsync>d__0.MoveNext()
--- End of stack trace from previous location where exception was thrown ---
   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
   at Microsoft.SharePoint.Client.ClientContext.<ExecuteQueryAsync>d__4.MoveNext()
--- End of stack trace from previous location where exception was thrown ---
   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.GetResult()
   at Microsoft.SharePoint.Client.ClientContextExtensions.<ExecuteQueryImplementation>d__7.MoveNext() in c:\pnpbuild\Core\OfficeDevPnP.Core\Extensions\ClientContextExtensions.cs:line 156
--- End of stack trace from previous location where exception was thrown ---
   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.GetResult()
   at Microsoft.SharePoint.Client.ClientContextExtensions.ExecuteQueryRetry(ClientRuntimeContext clientContext, Int32 retryCount, Int32 delay, String userAgent) in c:\pnpbuild\Core\OfficeDevPnP.Core\Extensions\ClientContextExtensions.cs:line 91
   at Microsoft.SharePoint.Client.TenantExtensions.DeleteSiteCollection(Tenant tenant, String siteFullUrl, Boolean useRecycleBin, Func`2 timeoutFunction) in c:\pnpbuild\Core\OfficeDevPnP.Core\Extensions\TenantExtensions.cs:line 413
   at OfficeDevPnP.Core.Tests.Framework.Functional.FunctionalTestBase.CleanupAllTestSiteCollections(ClientContext tenantContext) in c:\pnpbuild\Core\OfficeDevPnP.Core.Tests\Framework\Functional\FunctionalTestBase.cs:line 155
HResult = -2146233088
Applying template - 1/2 - Initializing engine
Applying template - 2/2 - Publishing
Getting template - 1/1 - Publishing
Type = System.Threading.ThreadAbortException
TraceCorrelationId = bfc8159f-20a5-1000-2e75-b541648c546f
Message = Thread was being aborted.
Source = mscorlib
TargetSite = Boolean ObjWait(Boolean, Int32, System.Object)
StackTrace =    at System.Threading.Monitor.ObjWait(Boolean exitContext, Int32 millisecondsTimeout, Object obj)
   at System.Threading.Monitor.Wait(Object obj, Int32 millisecondsTimeout, Boolean exitContext)
   at System.Threading.Monitor.Wait(Object obj, Int32 millisecondsTimeout)
   at System.Threading.ManualResetEventSlim.Wait(Int32 millisecondsTimeout, CancellationToken cancellationToken)
   at System.Threading.Tasks.Task.SpinThenBlockingWait(Int32 millisecondsTimeout, CancellationToken cancellationToken)
   at System.Threading.Tasks.Task.InternalWait(Int32 millisecondsTimeout, CancellationToken cancellationToken)
   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
   at Microsoft.SharePoint.Client.ClientObjectExtensions.EnsureProperty[T,TResult](T clientObject, Expression`1 propertySelector) in c:\pnpbuild\Core\OfficeDevPnP.Core\Extensions\ClientObjectExtensions.cs:line 70
   at OfficeDevPnP.Core.Framework.Provisioning.ObjectHandlers.ObjectPublishing.GetFiles(Folder folder) in c:\pnpbuild\Core\OfficeDevPnP.Core\Framework\Provisioning\ObjectHandlers\ObjectPublishing.cs:line 170
   at OfficeDevPnP.Core.Framework.Provisioning.ObjectHandlers.ObjectPublishing.GetFiles(Folder folder) in c:\pnpbuild\Core\OfficeDevPnP.Core\Framework\Provisioning\ObjectHandlers\ObjectPublishing.cs:line 175
   at OfficeDevPnP.Core.Framework.Provisioning.ObjectHandlers.ObjectPublishing.ExtractMasterPagesAndPageLayouts(Web web, ProvisioningTemplate template, PnPMonitoredScope scope, ProvisioningTemplateCreationInformation creationInfo) in c:\pnpbuild\Core\OfficeDevPnP.Core\Framework\Provisioning\ObjectHandlers\ObjectPublishing.cs:line 80
   at OfficeDevPnP.Core.Framework.Provisioning.ObjectHandlers.ObjectPublishing.ExtractObjects(Web web, ProvisioningTemplate template, ProvisioningTemplateCreationInformation creationInfo) in c:\pnpbuild\Core\OfficeDevPnP.Core\Framework\Provisioning\ObjectHandlers\ObjectPublishing.cs:line 52
   at OfficeDevPnP.Core.Framework.Provisioning.ObjectHandlers.SiteToTemplateConversion.GetRemoteTemplate(Web web, ProvisioningTemplateCreationInformation creationInfo) in c:\pnpbuild\Core\OfficeDevPnP.Core\Framework\Provisioning\ObjectHandlers\SiteToTemplateConversion.cs:line 152
   at Microsoft.SharePoint.Client.WebExtensions.GetProvisioningTemplate(Web web, ProvisioningTemplateCreationInformation creationInfo) in c:\pnpbuild\Core\OfficeDevPnP.Core\Extensions\WebExtensions.cs:line 1199
   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 59
HResult = -2146233040



Debug Trace:
vstest.console.exe Information: 0 : 2019-11-08 09:22:06.4259	[OfficeDevPnP.Core]	[0]	[Debug]	AuthenticationManager:EnsureToken(siteUrl:https://bertonline-admin.sharepoint.com,realm:d8623c9e-30c7-473a-83bc-d907df44a26e,appId:a6fdef05-ff36-4b45-870b-dfede84e2e26,appSecret:PRIVATE)	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:22:06.5510	[OfficeDevPnP.Core]	[0]	[Debug]	Lease expiration date: 11/8/2019 5:22:06 PM	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:22:09.2073	[SetSiteLockState: Current: {0} Target: {1}]	[0]	[Information]	Unlock	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:22:12.9663	[OfficeDevPnP.Core]	[0]	[Debug]	AuthenticationManager:EnsureToken(siteUrl:https://bertonline.sharepoint.com/sites/pnptest2,realm:d8623c9e-30c7-473a-83bc-d907df44a26e,appId:a6fdef05-ff36-4b45-870b-dfede84e2e26,appSecret:PRIVATE)	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:22:13.0756	[OfficeDevPnP.Core]	[0]	[Debug]	Lease expiration date: 11/8/2019 5:22:13 PM	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:31:43.5379	[OfficeDevPnP.Core]	[0]	[Debug]	AuthenticationManager:EnsureToken(siteUrl:https://bertonline.sharepoint.com/sites/TestPnPSC_12345_4472fa92-9c59-4bc2-ae7a-61f8bd0c4e5b,realm:d8623c9e-30c7-473a-83bc-d907df44a26e,appId:a6fdef05-ff36-4b45-870b-dfede84e2e26,appSecret:PRIVATE)	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:31:43.6629	[OfficeDevPnP.Core]	[0]	[Debug]	Lease expiration date: 11/8/2019 5:31:43 PM	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:31:43.8035	[OfficeDevPnP.Core]	[0]	[Information]	Creating web 'sub' with template ''.	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:31:54.1364	[OfficeDevPnP.Core]	[0]	[Debug]	AuthenticationManager:EnsureToken(siteUrl:https://bertonline.sharepoint.com/sites/TestPnPSC_12345_4472fa92-9c59-4bc2-ae7a-61f8bd0c4e5b,realm:d8623c9e-30c7-473a-83bc-d907df44a26e,appId:a6fdef05-ff36-4b45-870b-dfede84e2e26,appSecret:PRIVATE)	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:31:54.2459	[OfficeDevPnP.Core]	[0]	[Debug]	Lease expiration date: 11/8/2019 5:31:54 PM	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:31:54.2459	[OfficeDevPnP.Core]	[0]	[Information]	File publishing_add.xml retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:31:54.4177	[Provisioning]	[627]	[Debug]	Code execution scope started	0ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:31:54.4177	[Provisioning]	[627]	[Information]	ProgressDelegate registered	0ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:31:54.6365	[ProvisioningTemplate WebHook Call]	[627]	[Debug]	Code execution scope started	0ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:31:54.6365	[ProvisioningTemplate WebHook Call]	[627]	[Debug]	Code execution scope ended	0ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:31:54.6365	[ProvisioningTemplate WebHook Call]	[627]	[Debug]	Code execution scope started	0ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:31:54.6365	[ProvisioningTemplate WebHook Call]	[627]	[Debug]	Code execution scope ended	0ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:31:54.6365	[Publishing]	[627]	[Debug]	Code execution scope started	0ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:31:55.0896	[Publishing]	[627]	[Debug]	Making site compliant for publishing	461ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:31:55.1053	[OfficeDevPnP.Core]	[0]	[Information]	Activating feature f6924d36-2fa8-4f0b-b16d-06b7250180fa in web.	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:35:14.0520	[OfficeDevPnP.Core]	[0]	[Information]	Feature activation for f6924d36-2fa8-4f0b-b16d-06b7250180fa returned success.	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:35:14.0676	[OfficeDevPnP.Core]	[0]	[Information]	Activating feature 94c94ca6-b32f-4da9-a9e3-1f3d343d7ecb in web.	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:35:24.3787	[OfficeDevPnP.Core]	[0]	[Information]	Feature activation for 94c94ca6-b32f-4da9-a9e3-1f3d343d7ecb returned success.	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:35:24.8318	[Publishing]	[627]	[Debug]	Saving PnPDesignPackagev1.4-1.4.wsp to temporary file: C:\Users\pnpadmin.PNP\AppData\Local\Temp\PnPDesignPackagev1.4-1.4.wsp	210191ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:35:24.8474	[OfficeDevPnP.Core]	[0]	[Information]	File PnPDesignPackagev1.4-1.4.wsp retrieved from folder Templates	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:35:24.8474	[Publishing]	[627]	[Debug]	Installing design package	210205ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:35:24.8474	[OfficeDevPnP.Core]	[0]	[Information]	Installing sandbox solution 'PnPDesignPackagev1.4-1.4.wsp' to 'https://bertonline.sharepoint.com/sites/TestPnPSC_12345_4472fa92-9c59-4bc2-ae7a-61f8bd0c4e5b'.	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:35:24.9568	[OfficeDevPnP.Core]	[0]	[Debug]	Creating file info with Url 'PnPDesignPackagev1.4-1.4.wsp'	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:35:25.3631	[OfficeDevPnP.Core]	[0]	[Debug]	Uninstalling package 'PnPDesignPackagev1.4-1.4.wsp'	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:35:25.3631	[OfficeDevPnP.Core]	[0]	[Information]	Removing sandbox solution '38094c22-84c8-4d84-a2e4-19e934aa3fd0'.	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:35:25.7538	[OfficeDevPnP.Core]	[0]	[Debug]	Installing package 'PnPDesignPackagev1.4-1.4.wsp'	0ms	
vstest.console.exe Information: 0 : 2019-11-08 09:36:17.2199	[Publishing]	[627]	[Debug]	Code execution scope ended	262572ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:36:17.2199	[ProvisioningTemplate WebHook Call]	[627]	[Debug]	Code execution scope started	0ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:36:17.2199	[ProvisioningTemplate WebHook Call]	[627]	[Debug]	Code execution scope ended	0ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:36:17.2199	[ProvisioningTemplate WebHook Call]	[627]	[Debug]	Code execution scope started	0ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:36:17.2199	[ProvisioningTemplate WebHook Call]	[627]	[Debug]	Code execution scope ended	0ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:36:17.2199	[Persist Template Info]	[627]	[Debug]	Code execution scope started	0ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:36:18.1106	[Persist Template Info]	[627]	[Debug]	Code execution scope ended	895ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:36:18.1106	[ProvisioningTemplate WebHook Call]	[627]	[Debug]	Code execution scope started	0ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:36:18.1106	[ProvisioningTemplate WebHook Call]	[627]	[Debug]	Code execution scope ended	0ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:36:18.2043	[ProvisioningTemplate WebHook Call]	[627]	[Debug]	Code execution scope started	0ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:36:18.2043	[ProvisioningTemplate WebHook Call]	[627]	[Debug]	Code execution scope ended	0ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:36:18.2043	[Provisioning]	[627]	[Debug]	Code execution scope ended	263779ms	58ca1366-b52e-47d1-817d-7be8a2c42676
vstest.console.exe Information: 0 : 2019-11-08 09:36:18.5793	[Extraction]	[627]	[Debug]	Code execution scope started	0ms	d870d4eb-2ed3-4b2a-84d1-0dc8db37f950
vstest.console.exe Information: 0 : 2019-11-08 09:36:18.5793	[Extraction]	[627]	[Debug]	Base template available: STS0template	0ms	d870d4eb-2ed3-4b2a-84d1-0dc8db37f950
vstest.console.exe Information: 0 : 2019-11-08 09:36:18.5793	[Extraction]	[627]	[Debug]	ProgressDelegate registered	0ms	d870d4eb-2ed3-4b2a-84d1-0dc8db37f950
vstest.console.exe Information: 0 : 2019-11-08 09:36:19.0325	[Publishing]	[627]	[Debug]	Code execution scope started	0ms	d870d4eb-2ed3-4b2a-84d1-0dc8db37f950
vstest.console.exe Information: 0 : 2019-11-08 09:37:06.4306	[Publishing]	[627]	[Debug]	Code execution scope ended	47385ms	d870d4eb-2ed3-4b2a-84d1-0dc8db37f950
vstest.console.exe Information: 0 : 2019-11-08 09:37:06.4306	[Extraction]	[627]	[Debug]	Code execution scope ended	47853ms	d870d4eb-2ed3-4b2a-84d1-0dc8db37f950