1 Reply Latest reply on Jul 23, 2018 4:50 PM by brian.taylor.support

    Server is installing patches 12 hours late

    PeteCullen Rookie

      Hi all, I'm after some help please.
      Ran a deployment to our SQL servers on Friday the 20th, all of them ran fine but one of them decided to install patches and vmware tools at 15:00 instead of 03:00, which as you can imagine didn't go down too well.

      Contend of CSTDeploy.log file on the day shown below:

       

       

      2018-07-20T02:11:19.7811574Z 4910 S STDeploy.cpp:257 Entering wmain.
      2018-07-20T02:11:19.7811574Z 4910 I STDeploy.cpp:262 'C:\Windows\ProPatches\Staged\2018-07-20-T-02-11-13\stdeploy.exe' is starting, version: 9.3.2708.0.
      2018-07-20T02:11:19.7811574Z 4910 V DeployContext.cpp:259 STDeploy.exe command line args: 'package="C:\Windows\ProPatches\Staged\2018-07-20-T-02-11-13\deployPackage-70992.zip" "baseDir=C:\Windows\ProPatches" "removeStaging=yes"'
      2018-07-20T02:11:19.7811574Z 4910 S DeployExeStates.cpp:344 Entering STDeploy::CExtendedCmdLineHandlingPackageDeploy::ProcessExtendedCmdLineArguments.
      2018-07-20T02:11:19.7811574Z 4910 S DeployExeStates.cpp:344 Leaving STDeploy::CExtendedCmdLineHandlingPackageDeploy::ProcessExtendedCmdLineArguments.
      2018-07-20T02:11:19.7967837Z 4910 S DeployExeStates.cpp:401 Entering STDeploy::CInitialExecutionPackageDeploy::ServiceDeploymentSandboxes.
      2018-07-20T02:11:19.7967837Z 4910 I DeployExeStates.cpp:41 Sandbox service: removing old directory 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-13-T-02-14-18', age '6.997937' days
      2018-07-20T02:11:19.8124045Z 4910 V DeployExeStates.cpp:60 Created new deployment sandbox at 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19'
      2018-07-20T02:11:19.8124045Z 4910 S DeployExeStates.cpp:401 Leaving STDeploy::CInitialExecutionPackageDeploy::ServiceDeploymentSandboxes.
      2018-07-20T02:11:19.8124045Z 4910 V STDeploy.cpp:135 initialLogFilePath='C:\Windows\ProPatches\Staged\2018-07-20-T-02-11-13\STDeploy.log' permanentLogFilePath='C:\Windows\ProPatches\Logs\STDeploy.log' sandboxLogFilePath='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\STDeploy.log'
      2018-07-20T02:11:19.8124045Z 4910 I STDeploy.cpp:143 Preparing to Move initial trace logs to the permanent logfile at 'C:\Windows\ProPatches\Logs\STDeploy.log'.
      2018-07-20T02:11:19.8280363Z 4910 I STDeploy.cpp:167 Trace logging transferred from 'C:\Windows\ProPatches\Staged\2018-07-20-T-02-11-13\STDeploy.log'.
      2018-07-20T02:11:19.8280363Z 4910 V STDeploy.cpp:171 Moved initial trace logs to the logfile at 'C:\Windows\ProPatches\Logs\STDeploy.log'.
      2018-07-20T02:11:19.8592866Z 4910 I STDeploy.cpp:331 Relaunching STDeploy.exe to run out of a sandbox at 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19'.
      2018-07-20T02:11:20.1256513Z 1b78 S STDeploy.cpp:257 Entering wmain.
      2018-07-20T02:11:20.1256513Z 1b78 I STDeploy.cpp:262 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\stdeploy.exe' is starting, version: 9.3.2708.0.
      2018-07-20T02:11:20.1256513Z 1b78 V DeployContext.cpp:259 STDeploy.exe command line args: 'package="C:\Windows\ProPatches\Staged\2018-07-20-T-02-11-13\deployPackage-70992.zip" "baseDir=C:\Windows\ProPatches" "removeStaging=yes"'
      2018-07-20T02:11:20.1256513Z 1b78 S DeployExeStates.cpp:344 Entering STDeploy::CExtendedCmdLineHandlingPackageDeploy::ProcessExtendedCmdLineArguments.
      2018-07-20T02:11:20.1256513Z 1b78 S DeployExeStates.cpp:344 Leaving STDeploy::CExtendedCmdLineHandlingPackageDeploy::ProcessExtendedCmdLineArguments.
      2018-07-20T02:11:20.1415226Z 1b78 I STDeploy.cpp:358 C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\stdeploy.exe is now executing out of a sandbox: 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19'.
      2018-07-20T02:11:20.1415226Z 1b78 S DeployExeStates.cpp:409 Entering STDeploy::CInitialExecutionPackageDeploy::DoStatefulRemediateActions.
      2018-07-20T02:11:20.1415226Z 1b78 V DeployExeStates.cpp:84 Invoking remediation manager processing: userSuppliedFileName='deployPackage-70992.zip', userSuppliedPath='C:\Windows\ProPatches\Staged\2018-07-20-T-02-11-13', patchStorePath='C:\Windows\ProPatches\Patches', deployerCoreLogFilePath='C:\Windows\ProPatches\Logs\STDeployerCore.log', LaunchCount='0', LaunchReason='0', currentDirectory='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19', sandboxDirectory='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19'
      2018-07-20T02:11:20.1415226Z 1b78 I STPackageDeployer.cpp:1589 Moving files from stagingDirectory 'C:\Windows\ProPatches\Staged\2018-07-20-T-02-11-13' to sandbox directory 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19'
      2018-07-20T02:11:20.1415226Z 1b78 I DeploymentFileSetBuilder.cpp:107 Moving 'C:\Windows\ProPatches\Staged\2018-07-20-T-02-11-13\3c197e11-9480-484e-815a-1432a898bbe2.tracker' to 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\3c197e11-9480-484e-815a-1432a898bbe2.tracker'.
      2018-07-20T02:11:20.2968008Z 1b78 I DeploymentFileSetBuilder.cpp:107 Moving 'C:\Windows\ProPatches\Staged\2018-07-20-T-02-11-13\7z.dll' to 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\7z.dll'.
      2018-07-20T02:11:20.3280517Z 1b78 I DeploymentFileSetBuilder.cpp:107 Moving 'C:\Windows\ProPatches\Staged\2018-07-20-T-02-11-13\cl5.exe' to 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\cl5.exe'.
      2018-07-20T02:11:20.5060864Z 1b78 I DeploymentFileSetBuilder.cpp:107 Moving 'C:\Windows\ProPatches\Staged\2018-07-20-T-02-11-13\deployPackage-70992.zip' to 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\deployPackage-70992.zip'.
      2018-07-20T02:11:20.5624384Z 1b78 I DeploymentFileSetBuilder.cpp:107 Moving 'C:\Windows\ProPatches\Staged\2018-07-20-T-02-11-13\dplyevts.dll' to 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\dplyevts.dll'.
      2018-07-20T02:11:20.5946217Z 1b78 I DeploymentFileSetBuilder.cpp:107 Moving 'C:\Windows\ProPatches\Staged\2018-07-20-T-02-11-13\InstallPatches-70992.bat' to 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\InstallPatches-70992.bat'.
      2018-07-20T02:11:20.6405564Z 1b78 I DeploymentFileSetBuilder.cpp:107 Moving 'C:\Windows\ProPatches\Staged\2018-07-20-T-02-11-13\safereboot.exe' to 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\safereboot.exe'.
      2018-07-20T02:11:20.6561953Z 1b78 I DeploymentFileSetBuilder.cpp:107 Moving 'C:\Windows\ProPatches\Staged\2018-07-20-T-02-11-13\safereboot64.exe' to 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\safereboot64.exe'.
      2018-07-20T02:11:20.6561953Z 1b78 I DeploymentFileSetBuilder.cpp:107 Moving 'C:\Windows\ProPatches\Staged\2018-07-20-T-02-11-13\STDeploy.log' to 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\STDeploy.log'.
      2018-07-20T02:11:20.6718206Z 1b78 I DeploymentFileSetBuilder.cpp:107 Moving 'C:\Windows\ProPatches\Staged\2018-07-20-T-02-11-13\STDeployerCore.dll' to 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\STDeployerCore.dll'.
      2018-07-20T02:11:20.7030637Z 1b78 I STPackageDeployer.cpp:1595 Deleted staging directory 'C:\Windows\ProPatches\Staged\2018-07-20-T-02-11-13'
      2018-07-20T02:11:20.7030637Z 1b78 I STPackageDeployer.cpp:847 Deploy package 'deployPackage-70992.zip' successfully opened unsigned for package IO
      2018-07-20T02:11:20.7030637Z 1b78 I STPackageDeployer.cpp:192 All required parts found in the deployment package.
      2018-07-20T02:11:20.7030637Z 1b78 I STPackageDeployer.cpp:673 Deploy id ('3c197e11-9480-484e-815a-1432a898bbe2'), machine id ('70992'), deployerCoreVersion ('9.3.2708.0')
      2018-07-20T02:11:20.7030637Z 1b78 V SchedClt.cpp:148 CSchedClt(): schedulerType=0, jobCreator=STPackageDeployer, jobName=Scheduled Deployment {3c197e11-9480-484e-815a-1432a898bbe2}, comment=deleting job.
      2018-07-20T02:11:20.7030637Z 1b78 V Task.cpp:32 Task connected MS 2.0 scheduler
      2018-07-20T02:11:20.7030637Z 1b78 E Scheduler.cpp:767 NativeOS scheduler: Failed to delete jobName=Scheduled Deployment {3c197e11-9480-484e-815a-1432a898bbe2} with the scheduler 2.0 interface installed - will not try AT interface
      2018-07-20T02:11:20.7030637Z 1b78 V Task2.cpp:99 Task2 destructor
      2018-07-20T02:11:20.7030637Z 1b78 V SchedClt.cpp:148 CSchedClt(): schedulerType=1, jobCreator=STPackageDeployer, jobName=Scheduled Deployment {3c197e11-9480-484e-815a-1432a898bbe2}, comment=deleting job.
      2018-07-20T02:11:25.7507669Z 1b78 E SchedClt.cpp:486 ST scheduler: Failed to delete jobName='Scheduled Deployment {3c197e11-9480-484e-815a-1432a898bbe2}'
      2018-07-20T02:11:25.7507669Z 1b78 V SchedClt.cpp:148 CSchedClt(): schedulerType=0, jobCreator=STPackageDeployer, jobName=LaunchSTDeployToRetryDownloadsAtBootup (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T02:11:25.7507669Z 1b78 V Task.cpp:32 Task connected MS 2.0 scheduler
      2018-07-20T02:11:25.7507669Z 1b78 E Scheduler.cpp:767 NativeOS scheduler: Failed to delete jobName=LaunchSTDeployToRetryDownloadsAtBootup (3c197e11-9480-484e-815a-1432a898bbe2) with the scheduler 2.0 interface installed - will not try AT interface
      2018-07-20T02:11:25.7507669Z 1b78 V Task2.cpp:99 Task2 destructor
      2018-07-20T02:11:25.7507669Z 1b78 V SchedClt.cpp:148 CSchedClt(): schedulerType=1, jobCreator=STPackageDeployer, jobName=LaunchSTDeployToRetryDownloadsAtBootup (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T02:11:30.8284260Z 1b78 E SchedClt.cpp:486 ST scheduler: Failed to delete jobName='LaunchSTDeployToRetryDownloadsAtBootup (3c197e11-9480-484e-815a-1432a898bbe2)'
      2018-07-20T02:11:30.8284260Z 1b78 V SchedClt.cpp:148 CSchedClt(): schedulerType=0, jobCreator=STPackageDeployer, jobName=LaunchSTDeployToRetryDownloadsAtNextProgressiveTime (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T02:11:30.8284260Z 1b78 V Task.cpp:32 Task connected MS 2.0 scheduler
      2018-07-20T02:11:30.8284260Z 1b78 E Scheduler.cpp:767 NativeOS scheduler: Failed to delete jobName=LaunchSTDeployToRetryDownloadsAtNextProgressiveTime (3c197e11-9480-484e-815a-1432a898bbe2) with the scheduler 2.0 interface installed - will not try AT interface
      2018-07-20T02:11:30.8284260Z 1b78 V Task2.cpp:99 Task2 destructor
      2018-07-20T02:11:30.8284260Z 1b78 V SchedClt.cpp:148 CSchedClt(): schedulerType=1, jobCreator=STPackageDeployer, jobName=LaunchSTDeployToRetryDownloadsAtNextProgressiveTime (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T02:11:35.9067185Z 1b78 E SchedClt.cpp:486 ST scheduler: Failed to delete jobName='LaunchSTDeployToRetryDownloadsAtNextProgressiveTime (3c197e11-9480-484e-815a-1432a898bbe2)'
      2018-07-20T02:11:35.9067185Z 1b78 V SchedClt.cpp:148 CSchedClt(): schedulerType=0, jobCreator=STPackageDeployer, jobName=LaunchSTDeployForDeployAfterPreDeployReboot (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T02:11:35.9067185Z 1b78 V Task.cpp:32 Task connected MS 2.0 scheduler
      2018-07-20T02:11:35.9067185Z 1b78 E Scheduler.cpp:767 NativeOS scheduler: Failed to delete jobName=LaunchSTDeployForDeployAfterPreDeployReboot (3c197e11-9480-484e-815a-1432a898bbe2) with the scheduler 2.0 interface installed - will not try AT interface
      2018-07-20T02:11:35.9067185Z 1b78 V Task2.cpp:99 Task2 destructor
      2018-07-20T02:11:35.9067185Z 1b78 V SchedClt.cpp:148 CSchedClt(): schedulerType=1, jobCreator=STPackageDeployer, jobName=LaunchSTDeployForDeployAfterPreDeployReboot (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T02:11:40.9850143Z 1b78 E SchedClt.cpp:486 ST scheduler: Failed to delete jobName='LaunchSTDeployForDeployAfterPreDeployReboot (3c197e11-9480-484e-815a-1432a898bbe2)'
      2018-07-20T02:11:40.9850143Z 1b78 V SchedClt.cpp:148 CSchedClt(): schedulerType=0, jobCreator=STPackageDeployer, jobName=LaunchSTDeployForOnlineStatusRetry (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T02:11:40.9850143Z 1b78 V Task.cpp:32 Task connected MS 2.0 scheduler
      2018-07-20T02:11:40.9850143Z 1b78 E Scheduler.cpp:767 NativeOS scheduler: Failed to delete jobName=LaunchSTDeployForOnlineStatusRetry (3c197e11-9480-484e-815a-1432a898bbe2) with the scheduler 2.0 interface installed - will not try AT interface
      2018-07-20T02:11:40.9850143Z 1b78 V Task2.cpp:99 Task2 destructor
      2018-07-20T02:11:40.9850143Z 1b78 V SchedClt.cpp:148 CSchedClt(): schedulerType=1, jobCreator=STPackageDeployer, jobName=LaunchSTDeployForOnlineStatusRetry (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T02:11:46.0633143Z 1b78 E SchedClt.cpp:486 ST scheduler: Failed to delete jobName='LaunchSTDeployForOnlineStatusRetry (3c197e11-9480-484e-815a-1432a898bbe2)'
      2018-07-20T02:11:46.0633143Z 1b78 V SchedClt.cpp:148 CSchedClt(): schedulerType=0, jobCreator=STPackageDeployer, jobName=LaunchSTDeployForPostBootActions (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T02:11:46.0633143Z 1b78 V Task.cpp:32 Task connected MS 2.0 scheduler
      2018-07-20T02:11:46.0633143Z 1b78 E Scheduler.cpp:767 NativeOS scheduler: Failed to delete jobName=LaunchSTDeployForPostBootActions (3c197e11-9480-484e-815a-1432a898bbe2) with the scheduler 2.0 interface installed - will not try AT interface
      2018-07-20T02:11:46.0633143Z 1b78 V Task2.cpp:99 Task2 destructor
      2018-07-20T02:11:46.0633143Z 1b78 V SchedClt.cpp:148 CSchedClt(): schedulerType=1, jobCreator=STPackageDeployer, jobName=LaunchSTDeployForPostBootActions (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T02:11:51.1416136Z 1b78 E SchedClt.cpp:486 ST scheduler: Failed to delete jobName='LaunchSTDeployForPostBootActions (3c197e11-9480-484e-815a-1432a898bbe2)'
      2018-07-20T02:11:51.1416136Z 1b78 V SchedClt.cpp:148 CSchedClt(): schedulerType=0, jobCreator=STPackageDeployer, jobName=LaunchSTDeployToRetryFailedPostBootActions (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T02:11:51.1416136Z 1b78 V Task.cpp:32 Task connected MS 2.0 scheduler
      2018-07-20T02:11:51.1416136Z 1b78 E Scheduler.cpp:767 NativeOS scheduler: Failed to delete jobName=LaunchSTDeployToRetryFailedPostBootActions (3c197e11-9480-484e-815a-1432a898bbe2) with the scheduler 2.0 interface installed - will not try AT interface
      2018-07-20T02:11:51.1416136Z 1b78 V Task2.cpp:99 Task2 destructor
      2018-07-20T02:11:51.1416136Z 1b78 V SchedClt.cpp:148 CSchedClt(): schedulerType=1, jobCreator=STPackageDeployer, jobName=LaunchSTDeployToRetryFailedPostBootActions (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T02:11:56.2199313Z 1b78 E SchedClt.cpp:486 ST scheduler: Failed to delete jobName='LaunchSTDeployToRetryFailedPostBootActions (3c197e11-9480-484e-815a-1432a898bbe2)'
      2018-07-20T02:11:56.2199313Z 1b78 V SchedClt.cpp:148 CSchedClt(): schedulerType=0, jobCreator=STPackageDeployer, jobName=LaunchSafeRebootPostDeployment (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T02:11:56.2199313Z 1b78 V Task.cpp:32 Task connected MS 2.0 scheduler
      2018-07-20T02:11:56.2199313Z 1b78 E Scheduler.cpp:767 NativeOS scheduler: Failed to delete jobName=LaunchSafeRebootPostDeployment (3c197e11-9480-484e-815a-1432a898bbe2) with the scheduler 2.0 interface installed - will not try AT interface
      2018-07-20T02:11:56.2199313Z 1b78 V Task2.cpp:99 Task2 destructor
      2018-07-20T02:11:56.2199313Z 1b78 V SchedClt.cpp:148 CSchedClt(): schedulerType=1, jobCreator=STPackageDeployer, jobName=LaunchSafeRebootPostDeployment (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T02:12:01.2982118Z 1b78 E SchedClt.cpp:486 ST scheduler: Failed to delete jobName='LaunchSafeRebootPostDeployment (3c197e11-9480-484e-815a-1432a898bbe2)'
      2018-07-20T02:12:01.2982118Z 1b78 I STPackageDeployer.cpp:207 No support files in the deployment package.
      2018-07-20T02:12:01.2982118Z 1b78 V StatusClient.cpp:43 Attempting to load 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\dplyevts.dll'.
      2018-07-20T02:12:01.2982118Z 1b78 I StatusClient.cpp:49 Loaded 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\dplyevts.dll' (9.3.4510.0).
      2018-07-20T02:12:01.2982118Z 1b78 V DeployStatusReporter.cpp:309 Retrying online status reports. DeploymentId='3c197e11-9480-484e-815a-1432a898bbe2'
      2018-07-20T02:12:01.2982118Z 1b78 I STPackageDeployer.cpp:269 No vendor patch installers in the deployment package.
      2018-07-20T02:12:01.2982118Z 1b78 I STPackageDeployer.cpp:1527 Found the separately pushed 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\STDeployerCore.dll' file on disk
      2018-07-20T02:12:01.3138367Z 1b78 V STPackageDeployer.cpp:763 (patchSpecifiers.xml) StatusId from PatchDesc: patchId='0001e509-0000-0000-0000-000000000000', regionId='1033', instanceName='' => statusId='197745'
      2018-07-20T02:12:01.3138367Z 1b78 V STPackageDeployer.cpp:763 (patchSpecifiers.xml) StatusId from PatchDesc: patchId='0001e9c1-0000-0000-0000-000000000000', regionId='1033', instanceName='' => statusId='197746'
      2018-07-20T02:12:01.3138367Z 1b78 V STPackageDeployer.cpp:763 (patchSpecifiers.xml) StatusId from PatchDesc: patchId='0001ea1c-0000-0000-0000-000000000000', regionId='1033', instanceName='' => statusId='197747'
      2018-07-20T02:12:01.3138367Z 1b78 V STPackageDeployer.cpp:763 (patchSpecifiers.xml) StatusId from PatchDesc: patchId='0001e9bc-0000-0000-0000-000000000000', regionId='1033', instanceName='' => statusId='197748'
      2018-07-20T02:12:01.3138367Z 1b78 V STPackageDeployer.cpp:763 (patchSpecifiers.xml) StatusId from PatchDesc: patchId='0001e9bd-0000-0000-0000-000000000000', regionId='1033', instanceName='' => statusId='197749'
      2018-07-20T02:12:01.3294641Z 1b78 I DeployerClient.cpp:355 Loaded 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\STDeployerCore.dll' (9.3.2708.0).
      2018-07-20T02:12:01.3450911Z 1b78 I STPackageDeployer.cpp:1780 Created deployer client. If statically linked, tracing to 'C:\Windows\ProPatches\Logs\STDeployerCore.log'. Otherwise, tracing to STDeploy.log.
      2018-07-20T02:12:01.3450911Z 1b78 I STPackageDeployer.cpp:1832 Remote dialog request flag: 'false'
      2018-07-20T02:12:01.3450911Z 1b78 V STPackageDeployer.cpp:1842 Getting vendor patch download information.
      2018-07-20T02:12:01.3450911Z 1b78 I STPackageDeployer.cpp:1036 Not using distribution servers. Not downloading patches
      2018-07-20T02:12:01.3450911Z 1b78 V DeployStatusReporter.cpp:128 Queueing online machine status msg. DeploymentId='3c197e11-9480-484e-815a-1432a898bbe2', machineId='70992', status='43', isFinal='false'
      2018-07-20T02:12:01.3450911Z 1b78 S StatusClient.cpp:109 Entering STDeployment::CStatusClient::ReportMachineStatusOnline.
      2018-07-20T02:12:03.6576700Z 1b78 I STPackageDeployer.cpp:1931 Deploying patches...
      2018-07-20T02:12:03.6576700Z 1b78 V STPackageDeployer.cpp:1936 statusId='197745' <= StatusId from PatchDesc: patchId='0001e509-0000-0000-0000-000000000000', regionId='1033', instanceName=''
      2018-07-20T02:12:03.6576700Z 1b78 I STPackageDeployer.cpp:307 Deploy arguments: machineName '192.168.11.195', patchId '0001e509-0000-0000-0000-000000000000', isServicePack 'false', regionId '1033'
      2018-07-20T02:12:03.6576700Z 1b78 V DeployStatusReporter.cpp:239 Queueing online patch progress msg. DeploymentId='3c197e11-9480-484e-815a-1432a898bbe2', statusId='197745', patchStatus='4', isFinal='false'
      2018-07-20T02:12:03.6576700Z 1b78 S StatusClient.cpp:90 Entering STDeployment::CStatusClient::ReportPatchStatusOnline.
      2018-07-20T14:12:14.0685379Z 1b78 V DeployStatusReporter.cpp:257 Queueing online patch progress msg. DeploymentId='3c197e11-9480-484e-815a-1432a898bbe2', statusId='197745', cookedResult='1', isFinal='true'
      2018-07-20T14:12:14.0685379Z 1b78 S StatusClient.cpp:99 Entering STDeployment::CStatusClient::ReportPatchStatusOnline.
      2018-07-20T14:12:16.2248617Z 1b78 I STPackageDeployer.cpp:1235 Patch file 'C:\Windows\ProPatches\Patches\WinSCP-5.13.3-Setup.exe' install operation completed. Cooked result='1'. RebootRequired='false'
      2018-07-20T14:12:16.2248617Z 1b78 V STPackageDeployer.cpp:1936 statusId='197748' <= StatusId from PatchDesc: patchId='0001e9bc-0000-0000-0000-000000000000', regionId='1033', instanceName=''
      2018-07-20T14:12:16.2248617Z 1b78 I STPackageDeployer.cpp:307 Deploy arguments: machineName '192.168.11.195', patchId '0001e9bc-0000-0000-0000-000000000000', isServicePack 'false', regionId '1033'
      2018-07-20T14:12:16.2248617Z 1b78 V DeployStatusReporter.cpp:239 Queueing online patch progress msg. DeploymentId='3c197e11-9480-484e-815a-1432a898bbe2', statusId='197748', patchStatus='4', isFinal='false'
      2018-07-20T14:12:16.2248617Z 1b78 S StatusClient.cpp:90 Entering STDeployment::CStatusClient::ReportPatchStatusOnline.
      2018-07-20T14:12:24.0845094Z 1b78 V DeployStatusReporter.cpp:257 Queueing online patch progress msg. DeploymentId='3c197e11-9480-484e-815a-1432a898bbe2', statusId='197748', cookedResult='1', isFinal='true'
      2018-07-20T14:12:24.0845094Z 1b78 S StatusClient.cpp:99 Entering STDeployment::CStatusClient::ReportPatchStatusOnline.
      2018-07-20T14:12:24.1001388Z 1b78 I STPackageDeployer.cpp:1235 Patch file 'C:\Windows\ProPatches\Patches\AspNetWebToolsVS2013-KB4339279.exe' install operation completed. Cooked result='1'. RebootRequired='false'
      2018-07-20T14:12:24.1001388Z 1b78 V STPackageDeployer.cpp:1936 statusId='197749' <= StatusId from PatchDesc: patchId='0001e9bd-0000-0000-0000-000000000000', regionId='1033', instanceName=''
      2018-07-20T14:12:24.1001388Z 1b78 I STPackageDeployer.cpp:307 Deploy arguments: machineName '192.168.11.195', patchId '0001e9bd-0000-0000-0000-000000000000', isServicePack 'false', regionId '1033'
      2018-07-20T14:12:24.1001388Z 1b78 V DeployStatusReporter.cpp:239 Queueing online patch progress msg. DeploymentId='3c197e11-9480-484e-815a-1432a898bbe2', statusId='197749', patchStatus='4', isFinal='false'
      2018-07-20T14:12:24.1001388Z 1b78 S StatusClient.cpp:90 Entering STDeployment::CStatusClient::ReportPatchStatusOnline.
      2018-07-20T14:12:29.0690552Z 1b78 V DeployStatusReporter.cpp:257 Queueing online patch progress msg. DeploymentId='3c197e11-9480-484e-815a-1432a898bbe2', statusId='197749', cookedResult='1', isFinal='true'
      2018-07-20T14:12:29.0690552Z 1b78 S StatusClient.cpp:99 Entering STDeployment::CStatusClient::ReportPatchStatusOnline.
      2018-07-20T14:12:29.1159352Z 1b78 I STPackageDeployer.cpp:1235 Patch file 'C:\Windows\ProPatches\Patches\AspNetWebFrameworksToolsVS2013-KB4339279.exe' install operation completed. Cooked result='1'. RebootRequired='false'
      2018-07-20T14:12:29.1159352Z 1b78 V STPackageDeployer.cpp:1936 statusId='197746' <= StatusId from PatchDesc: patchId='0001e9c1-0000-0000-0000-000000000000', regionId='1033', instanceName=''
      2018-07-20T14:12:29.1159352Z 1b78 I STPackageDeployer.cpp:307 Deploy arguments: machineName '192.168.11.195', patchId '0001e9c1-0000-0000-0000-000000000000', isServicePack 'false', regionId '1033'
      2018-07-20T14:12:29.1159352Z 1b78 V DeployStatusReporter.cpp:239 Queueing online patch progress msg. DeploymentId='3c197e11-9480-484e-815a-1432a898bbe2', statusId='197746', patchStatus='4', isFinal='false'
      2018-07-20T14:12:29.1159352Z 1b78 S StatusClient.cpp:90 Entering STDeployment::CStatusClient::ReportPatchStatusOnline.
      2018-07-20T14:14:15.3148538Z 1b78 V DeployStatusReporter.cpp:257 Queueing online patch progress msg. DeploymentId='3c197e11-9480-484e-815a-1432a898bbe2', statusId='197746', cookedResult='6', isFinal='true'
      2018-07-20T14:14:15.3148538Z 1b78 S StatusClient.cpp:99 Entering STDeployment::CStatusClient::ReportPatchStatusOnline.
      2018-07-20T14:14:17.4099783Z 1b78 I STPackageDeployer.cpp:1235 Patch file 'C:\Windows\ProPatches\Patches\VMware-tools-10.3.0-8931395-x86_64.exe' install operation completed. Cooked result='6'. RebootRequired='true'
      2018-07-20T14:14:17.4099783Z 1b78 V STPackageDeployer.cpp:1936 statusId='197747' <= StatusId from PatchDesc: patchId='0001ea1c-0000-0000-0000-000000000000', regionId='1033', instanceName=''
      2018-07-20T14:14:17.4099783Z 1b78 I STPackageDeployer.cpp:307 Deploy arguments: machineName '192.168.11.195', patchId '0001ea1c-0000-0000-0000-000000000000', isServicePack 'false', regionId '1033'
      2018-07-20T14:14:17.4099783Z 1b78 V DeployStatusReporter.cpp:239 Queueing online patch progress msg. DeploymentId='3c197e11-9480-484e-815a-1432a898bbe2', statusId='197747', patchStatus='4', isFinal='false'
      2018-07-20T14:14:17.4099783Z 1b78 S StatusClient.cpp:90 Entering STDeployment::CStatusClient::ReportPatchStatusOnline.
      2018-07-20T14:14:41.1131532Z 1b78 V DeployStatusReporter.cpp:257 Queueing online patch progress msg. DeploymentId='3c197e11-9480-484e-815a-1432a898bbe2', statusId='197747', cookedResult='5', isFinal='true'
      2018-07-20T14:14:41.1131532Z 1b78 S StatusClient.cpp:99 Entering STDeployment::CStatusClient::ReportPatchStatusOnline.
      2018-07-20T14:14:41.1444398Z 1b78 I STPackageDeployer.cpp:1235 Patch file 'C:\Windows\ProPatches\Patches\Wireshark-win64-2.6.2.exe' install operation completed. Cooked result='5'. RebootRequired='false'
      2018-07-20T14:14:41.1444398Z 1b78 I STPackageDeployer.cpp:1963 Done deploying patches...
      2018-07-20T14:14:41.1444398Z 1b78 I STPackageDeployer.cpp:1965 Deploying product instances patches...
      2018-07-20T14:14:41.1444398Z 1b78 I STPackageDeployer.cpp:2005 Done deploying product instances patches...
      2018-07-20T14:14:41.1444398Z 1b78 W SingleInstanceLock.cpp:28 Waiting for another deployment to finish.
      2018-07-20T14:14:41.1444398Z 1b78 I SingleInstanceLock.cpp:36 Exclusively continuing deployment.
      2018-07-20T14:14:41.1444398Z 1b78 V STPackageDeployer.cpp:85 Initiating patch store servicing.
      2018-07-20T14:14:41.1444398Z 1b78 V STPackageDeployer.cpp:106 Patch store servicing complete.
      2018-07-20T14:14:41.1600763Z 1b78 I STPackageDeployer.cpp:1378 Scheduling a relaunch of STDeploy.exe at next bootup to perform post-boot actions processing
      2018-07-20T14:14:41.1600763Z 1b78 V SchedClt.cpp:148 CSchedClt(): schedulerType=1, jobCreator=STPackageDeployer, jobName=LaunchSTDeployForPostBootActions (3c197e11-9480-484e-815a-1432a898bbe2), comment=Schedule STDeploy.exe to run at next bootup to perform post-boot actions.
      2018-07-20T14:14:41.1600763Z 1b78 I STPackageDeployer.cpp:974 'STDeploy.exe package="deployPackage-70992.zip" relaunchSandbox="C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19" relaunchReason="afterPostDeploymentReboot=1"' scheduled to run on next boot-up.
      2018-07-20T14:14:41.1600763Z 1b78 I STPackageDeployer.cpp:422 Reporting pending reboot to status handler.
      2018-07-20T14:14:41.1600763Z 1b78 V DeployStatusReporter.cpp:17 CDeployStatusReporter@MakePostBootActionSequence: Loading existing task-xml filename='PostBootTasks.xml'.
      2018-07-20T14:14:41.1600763Z 1b78 S PostBootActionSequence.cpp:332 Entering STDeployment::CPostBootActionSequence::Load.
      2018-07-20T14:14:41.1600763Z 1b78 S PostBootActionSequence.cpp:347 Entering STDeployment::CPostBootActionSequence::ReadXml.
      2018-07-20T14:14:41.1600763Z 1b78 S PostBootActionSequence.cpp:347 Leaving STDeployment::CPostBootActionSequence::ReadXml.
      2018-07-20T14:14:41.1600763Z 1b78 S PostBootActionSequence.cpp:332 Leaving STDeployment::CPostBootActionSequence::Load.
      2018-07-20T14:14:41.1600763Z 1b78 S PostBootActionSequence.cpp:546 Entering STDeployment::CPostBootActionSequence::Save.
      2018-07-20T14:14:41.1600763Z 1b78 S PostBootActionSequence.cpp:390 Entering STDeployment::CPostBootActionSequence::WriteXml.
      2018-07-20T14:14:41.1600763Z 1b78 S PostBootActionSequence.cpp:390 Leaving STDeployment::CPostBootActionSequence::WriteXml.
      2018-07-20T14:14:41.1600763Z 1b78 S PostBootActionSequence.cpp:546 Leaving STDeployment::CPostBootActionSequence::Save.
      2018-07-20T14:14:41.1600763Z 1b78 V DeployStatusReporter.cpp:226 Queued a PostBootTasks.xml action: 'Report machine status finished to the online status handler'. DeploymentId='3c197e11-9480-484e-815a-1432a898bbe2', machineId='70992', machineStatus='34'
      2018-07-20T14:14:41.1600763Z 1b78 V DeployStatusReporter.cpp:128 Queueing online machine status msg. DeploymentId='3c197e11-9480-484e-815a-1432a898bbe2', machineId='70992', status='34', isFinal='false'
      2018-07-20T14:14:41.1600763Z 1b78 S StatusClient.cpp:109 Entering STDeployment::CStatusClient::ReportMachineStatusOnline.
      2018-07-20T14:14:41.1913307Z 1b78 I STPackageDeployer.cpp:478 Launching SafeReboot. deployerSpecifiedRebootRequired=true
      2018-07-20T14:14:41.1913307Z 1b78 I STPackageDeployer.cpp:390 SafeReboot command line: '"C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\SafeReboot.exe" -o 0 -requestor 1 -s 2 -m 1 -w 60 -n "Safe Reboot" -u "MEARSDOM\admin_cullenp" -power 4'
      2018-07-20T14:14:41.2695069Z 1b78 S DeployExeStates.cpp:409 Leaving STDeploy::CInitialExecutionPackageDeploy::DoStatefulRemediateActions.
      2018-07-20T14:14:41.2695069Z 1b78 I STDeploy.cpp:365 Current remediation phase completed. Process exit code: 3010.
      2018-07-20T14:14:41.2695069Z 1b78 S STDeploy.cpp:257 Leaving wmain.
      2018-07-20T14:18:40.6984133Z 08e8 S STDeploy.cpp:257 Entering wmain.
      2018-07-20T14:18:40.6984133Z 08e8 I STDeploy.cpp:262 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\STDeploy.exe' is starting, version: 9.3.2708.0.
      2018-07-20T14:18:40.6984133Z 08e8 V DeployContext.cpp:259 STDeploy.exe command line args: 'package="C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\deployPackage-70992.zip" "relaunchSandbox=C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19" "relaunchReason=afterPostDeploymentReboot=1"'
      2018-07-20T14:18:40.6984133Z 08e8 S DeployExeStates.cpp:344 Entering STDeploy::CExtendedCmdLineHandlingPackageDeploy::ProcessExtendedCmdLineArguments.
      2018-07-20T14:18:40.7140392Z 08e8 S DeployExeStates.cpp:344 Leaving STDeploy::CExtendedCmdLineHandlingPackageDeploy::ProcessExtendedCmdLineArguments.
      2018-07-20T14:18:40.7452871Z 08e8 I STDeploy.cpp:358 C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\STDeploy.exe is now executing out of a sandbox: 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19'.
      2018-07-20T14:18:40.7452871Z 08e8 S DeployExeStates.cpp:418 Entering STDeploy::CRelaunchedExecutionPackageDeploy::DoStatefulRemediateActions.
      2018-07-20T14:18:40.7452871Z 08e8 V DeployExeStates.cpp:84 Invoking remediation manager processing: userSuppliedFileName='deployPackage-70992.zip', userSuppliedPath='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19', patchStorePath='C:\Windows\ProPatches\Patches', deployerCoreLogFilePath='C:\Windows\ProPatches\Logs\STDeployerCore.log', LaunchCount='1', LaunchReason='5', currentDirectory='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19', sandboxDirectory='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19'
      2018-07-20T14:18:40.7609139Z 08e8 I STPackageDeployer.cpp:847 Deploy package 'deployPackage-70992.zip' successfully opened unsigned for package IO
      2018-07-20T14:18:40.7609139Z 08e8 I STPackageDeployer.cpp:192 All required parts found in the deployment package.
      2018-07-20T14:18:40.7609139Z 08e8 I STPackageDeployer.cpp:673 Deploy id ('3c197e11-9480-484e-815a-1432a898bbe2'), machine id ('70992'), deployerCoreVersion ('9.3.2708.0')
      2018-07-20T14:18:40.7609139Z 08e8 V SchedClt.cpp:148 CSchedClt(): schedulerType=0, jobCreator=STPackageDeployer, jobName=Scheduled Deployment {3c197e11-9480-484e-815a-1432a898bbe2}, comment=deleting job.
      2018-07-20T14:18:40.7926497Z 08e8 V Task.cpp:32 Task connected MS 2.0 scheduler
      2018-07-20T14:18:40.7926497Z 08e8 E Scheduler.cpp:767 NativeOS scheduler: Failed to delete jobName=Scheduled Deployment {3c197e11-9480-484e-815a-1432a898bbe2} with the scheduler 2.0 interface installed - will not try AT interface
      2018-07-20T14:18:40.7926497Z 08e8 V Task2.cpp:99 Task2 destructor
      2018-07-20T14:18:40.7926497Z 08e8 V SchedClt.cpp:148 CSchedClt(): schedulerType=1, jobCreator=STPackageDeployer, jobName=Scheduled Deployment {3c197e11-9480-484e-815a-1432a898bbe2}, comment=deleting job.
      2018-07-20T14:18:45.8703005Z 08e8 E SchedClt.cpp:486 ST scheduler: Failed to delete jobName='Scheduled Deployment {3c197e11-9480-484e-815a-1432a898bbe2}'
      2018-07-20T14:18:45.8703005Z 08e8 V SchedClt.cpp:148 CSchedClt(): schedulerType=0, jobCreator=STPackageDeployer, jobName=LaunchSTDeployToRetryDownloadsAtBootup (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T14:18:45.8703005Z 08e8 V Task.cpp:32 Task connected MS 2.0 scheduler
      2018-07-20T14:18:45.8703005Z 08e8 E Scheduler.cpp:767 NativeOS scheduler: Failed to delete jobName=LaunchSTDeployToRetryDownloadsAtBootup (3c197e11-9480-484e-815a-1432a898bbe2) with the scheduler 2.0 interface installed - will not try AT interface
      2018-07-20T14:18:45.8703005Z 08e8 V Task2.cpp:99 Task2 destructor
      2018-07-20T14:18:45.8703005Z 08e8 V SchedClt.cpp:148 CSchedClt(): schedulerType=1, jobCreator=STPackageDeployer, jobName=LaunchSTDeployToRetryDownloadsAtBootup (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T14:18:50.9484443Z 08e8 E SchedClt.cpp:486 ST scheduler: Failed to delete jobName='LaunchSTDeployToRetryDownloadsAtBootup (3c197e11-9480-484e-815a-1432a898bbe2)'
      2018-07-20T14:18:50.9484443Z 08e8 V SchedClt.cpp:148 CSchedClt(): schedulerType=0, jobCreator=STPackageDeployer, jobName=LaunchSTDeployToRetryDownloadsAtNextProgressiveTime (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T14:18:50.9484443Z 08e8 V Task.cpp:32 Task connected MS 2.0 scheduler
      2018-07-20T14:18:50.9484443Z 08e8 E Scheduler.cpp:767 NativeOS scheduler: Failed to delete jobName=LaunchSTDeployToRetryDownloadsAtNextProgressiveTime (3c197e11-9480-484e-815a-1432a898bbe2) with the scheduler 2.0 interface installed - will not try AT interface
      2018-07-20T14:18:50.9484443Z 08e8 V Task2.cpp:99 Task2 destructor
      2018-07-20T14:18:50.9484443Z 08e8 V SchedClt.cpp:148 CSchedClt(): schedulerType=1, jobCreator=STPackageDeployer, jobName=LaunchSTDeployToRetryDownloadsAtNextProgressiveTime (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T14:18:56.0265731Z 08e8 E SchedClt.cpp:486 ST scheduler: Failed to delete jobName='LaunchSTDeployToRetryDownloadsAtNextProgressiveTime (3c197e11-9480-484e-815a-1432a898bbe2)'
      2018-07-20T14:18:56.0265731Z 08e8 V SchedClt.cpp:148 CSchedClt(): schedulerType=0, jobCreator=STPackageDeployer, jobName=LaunchSTDeployForDeployAfterPreDeployReboot (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T14:18:56.0265731Z 08e8 V Task.cpp:32 Task connected MS 2.0 scheduler
      2018-07-20T14:18:56.0265731Z 08e8 E Scheduler.cpp:767 NativeOS scheduler: Failed to delete jobName=LaunchSTDeployForDeployAfterPreDeployReboot (3c197e11-9480-484e-815a-1432a898bbe2) with the scheduler 2.0 interface installed - will not try AT interface
      2018-07-20T14:18:56.0265731Z 08e8 V Task2.cpp:99 Task2 destructor
      2018-07-20T14:18:56.0265731Z 08e8 V SchedClt.cpp:148 CSchedClt(): schedulerType=1, jobCreator=STPackageDeployer, jobName=LaunchSTDeployForDeployAfterPreDeployReboot (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T14:19:01.1048893Z 08e8 E SchedClt.cpp:486 ST scheduler: Failed to delete jobName='LaunchSTDeployForDeployAfterPreDeployReboot (3c197e11-9480-484e-815a-1432a898bbe2)'
      2018-07-20T14:19:01.1048893Z 08e8 V SchedClt.cpp:148 CSchedClt(): schedulerType=0, jobCreator=STPackageDeployer, jobName=LaunchSTDeployForOnlineStatusRetry (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T14:19:01.1048893Z 08e8 V Task.cpp:32 Task connected MS 2.0 scheduler
      2018-07-20T14:19:01.1048893Z 08e8 E Scheduler.cpp:767 NativeOS scheduler: Failed to delete jobName=LaunchSTDeployForOnlineStatusRetry (3c197e11-9480-484e-815a-1432a898bbe2) with the scheduler 2.0 interface installed - will not try AT interface
      2018-07-20T14:19:01.1048893Z 08e8 V Task2.cpp:99 Task2 destructor
      2018-07-20T14:19:01.1048893Z 08e8 V SchedClt.cpp:148 CSchedClt(): schedulerType=1, jobCreator=STPackageDeployer, jobName=LaunchSTDeployForOnlineStatusRetry (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T14:19:06.1883248Z 08e8 E SchedClt.cpp:486 ST scheduler: Failed to delete jobName='LaunchSTDeployForOnlineStatusRetry (3c197e11-9480-484e-815a-1432a898bbe2)'
      2018-07-20T14:19:06.1883248Z 08e8 V SchedClt.cpp:148 CSchedClt(): schedulerType=0, jobCreator=STPackageDeployer, jobName=LaunchSTDeployForPostBootActions (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T14:19:06.1883248Z 08e8 V Task.cpp:32 Task connected MS 2.0 scheduler
      2018-07-20T14:19:06.1883248Z 08e8 E Scheduler.cpp:767 NativeOS scheduler: Failed to delete jobName=LaunchSTDeployForPostBootActions (3c197e11-9480-484e-815a-1432a898bbe2) with the scheduler 2.0 interface installed - will not try AT interface
      2018-07-20T14:19:06.1883248Z 08e8 V Task2.cpp:99 Task2 destructor
      2018-07-20T14:19:06.1883248Z 08e8 V SchedClt.cpp:148 CSchedClt(): schedulerType=1, jobCreator=STPackageDeployer, jobName=LaunchSTDeployForPostBootActions (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T14:19:11.2717624Z 08e8 E SchedClt.cpp:486 ST scheduler: Failed to delete jobName='LaunchSTDeployForPostBootActions (3c197e11-9480-484e-815a-1432a898bbe2)'
      2018-07-20T14:19:11.2717624Z 08e8 V SchedClt.cpp:148 CSchedClt(): schedulerType=0, jobCreator=STPackageDeployer, jobName=LaunchSTDeployToRetryFailedPostBootActions (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T14:19:11.2717624Z 08e8 V Task.cpp:32 Task connected MS 2.0 scheduler
      2018-07-20T14:19:11.2717624Z 08e8 E Scheduler.cpp:767 NativeOS scheduler: Failed to delete jobName=LaunchSTDeployToRetryFailedPostBootActions (3c197e11-9480-484e-815a-1432a898bbe2) with the scheduler 2.0 interface installed - will not try AT interface
      2018-07-20T14:19:11.2717624Z 08e8 V Task2.cpp:99 Task2 destructor
      2018-07-20T14:19:11.2717624Z 08e8 V SchedClt.cpp:148 CSchedClt(): schedulerType=1, jobCreator=STPackageDeployer, jobName=LaunchSTDeployToRetryFailedPostBootActions (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T14:19:16.3551976Z 08e8 E SchedClt.cpp:486 ST scheduler: Failed to delete jobName='LaunchSTDeployToRetryFailedPostBootActions (3c197e11-9480-484e-815a-1432a898bbe2)'
      2018-07-20T14:19:16.3551976Z 08e8 V SchedClt.cpp:148 CSchedClt(): schedulerType=0, jobCreator=STPackageDeployer, jobName=LaunchSafeRebootPostDeployment (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T14:19:16.3551976Z 08e8 V Task.cpp:32 Task connected MS 2.0 scheduler
      2018-07-20T14:19:16.3551976Z 08e8 E Scheduler.cpp:767 NativeOS scheduler: Failed to delete jobName=LaunchSafeRebootPostDeployment (3c197e11-9480-484e-815a-1432a898bbe2) with the scheduler 2.0 interface installed - will not try AT interface
      2018-07-20T14:19:16.3551976Z 08e8 V Task2.cpp:99 Task2 destructor
      2018-07-20T14:19:16.3551976Z 08e8 V SchedClt.cpp:148 CSchedClt(): schedulerType=1, jobCreator=STPackageDeployer, jobName=LaunchSafeRebootPostDeployment (3c197e11-9480-484e-815a-1432a898bbe2), comment=deleting job.
      2018-07-20T14:19:21.4386421Z 08e8 E SchedClt.cpp:486 ST scheduler: Failed to delete jobName='LaunchSafeRebootPostDeployment (3c197e11-9480-484e-815a-1432a898bbe2)'
      2018-07-20T14:19:21.4386421Z 08e8 I STPackageDeployer.cpp:207 No support files in the deployment package.
      2018-07-20T14:19:21.4699191Z 08e8 V StatusClient.cpp:43 Attempting to load 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\dplyevts.dll'.
      2018-07-20T14:19:21.5011979Z 08e8 I StatusClient.cpp:49 Loaded 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\dplyevts.dll' (9.3.4510.0).
      2018-07-20T14:19:21.5011979Z 08e8 V DeployStatusReporter.cpp:309 Retrying online status reports. DeploymentId='3c197e11-9480-484e-815a-1432a898bbe2'
      2018-07-20T14:19:21.5168486Z 08e8 I STPackageDeployer.cpp:1527 Found the separately pushed 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\STDeployerCore.dll' file on disk
      2018-07-20T14:19:21.5794041Z 08e8 V STPackageDeployer.cpp:763 (patchSpecifiers.xml) StatusId from PatchDesc: patchId='0001e509-0000-0000-0000-000000000000', regionId='1033', instanceName='' => statusId='197745'
      2018-07-20T14:19:21.5794041Z 08e8 V STPackageDeployer.cpp:763 (patchSpecifiers.xml) StatusId from PatchDesc: patchId='0001e9c1-0000-0000-0000-000000000000', regionId='1033', instanceName='' => statusId='197746'
      2018-07-20T14:19:21.5794041Z 08e8 V STPackageDeployer.cpp:763 (patchSpecifiers.xml) StatusId from PatchDesc: patchId='0001ea1c-0000-0000-0000-000000000000', regionId='1033', instanceName='' => statusId='197747'
      2018-07-20T14:19:21.5794041Z 08e8 V STPackageDeployer.cpp:763 (patchSpecifiers.xml) StatusId from PatchDesc: patchId='0001e9bc-0000-0000-0000-000000000000', regionId='1033', instanceName='' => statusId='197748'
      2018-07-20T14:19:21.5794041Z 08e8 V STPackageDeployer.cpp:763 (patchSpecifiers.xml) StatusId from PatchDesc: patchId='0001e9bd-0000-0000-0000-000000000000', regionId='1033', instanceName='' => statusId='197749'
      2018-07-20T14:19:21.5794041Z 08e8 I DeployerClient.cpp:355 Loaded 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\STDeployerCore.dll' (9.3.2708.0).
      2018-07-20T14:19:21.5970415Z 08e8 I STPackageDeployer.cpp:1780 Created deployer client. If statically linked, tracing to 'C:\Windows\ProPatches\Logs\STDeployerCore.log'. Otherwise, tracing to STDeploy.log.
      2018-07-20T14:19:21.5970415Z 08e8 S PostBootLauncher.cpp:32 Entering STDeploy::CPostBootLauncher::LaunchPostBootActions.
      2018-07-20T14:19:21.5970415Z 08e8 S PostBootActionSequence.cpp:332 Entering STDeployment::CPostBootActionSequence::Load.
      2018-07-20T14:19:21.6106921Z 08e8 S PostBootActionSequence.cpp:347 Entering STDeployment::CPostBootActionSequence::ReadXml.
      2018-07-20T14:19:21.6106921Z 08e8 S PostBootActionSequence.cpp:347 Leaving STDeployment::CPostBootActionSequence::ReadXml.
      2018-07-20T14:19:21.6106921Z 08e8 S PostBootActionSequence.cpp:332 Leaving STDeployment::CPostBootActionSequence::Load.
      2018-07-20T14:19:21.6106921Z 08e8 S PostBootActionSequence.cpp:415 Entering STDeployment::CPostBootActionSequence::ProcessPostRebootTasksImpl.
      2018-07-20T14:19:21.6106921Z 08e8 S PostBootActionSequence.cpp:560 Entering STDeployment::CPostBootActionSequence::DispatchPostRebootTask.
      2018-07-20T14:19:21.6106921Z 08e8 S PostBootActionSequence.cpp:89 Entering `anonymous-namespace'::ProcessRegistryBreadCrumbUpdate.
      2018-07-20T14:19:21.6106921Z 08e8 I PatchBreadCrumbAccessor.cpp:261 Recording post-reboot registry breadcrumb=Finished for patchId=0001e9c1-0000-0000-0000-000000000000.
      2018-07-20T14:19:21.6106921Z 08e8 S PostBootActionSequence.cpp:89 Leaving `anonymous-namespace'::ProcessRegistryBreadCrumbUpdate.
      2018-07-20T14:19:21.6106921Z 08e8 S PostBootActionSequence.cpp:560 Leaving STDeployment::CPostBootActionSequence::DispatchPostRebootTask.
      2018-07-20T14:19:21.6106921Z 08e8 I PostBootActionSequence.cpp:433 Command RECORD_PATCH_INSTALL_BREADCRUMB_IN_REGISTRY completed
      2018-07-20T14:19:21.6106921Z 08e8 S PostBootActionSequence.cpp:560 Entering STDeployment::CPostBootActionSequence::DispatchPostRebootTask.
      2018-07-20T14:19:21.6106921Z 08e8 S PostBootActionSequence.cpp:273 Entering `anonymous-namespace'::ProcessStatusReport.
      2018-07-20T14:19:21.6106921Z 08e8 V StatusClient.cpp:43 Attempting to load 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\dplyevts.dll'.
      2018-07-20T14:19:21.6106921Z 08e8 I StatusClient.cpp:49 Loaded 'C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\dplyevts.dll' (9.3.4510.0).
      2018-07-20T14:19:21.6106921Z 08e8 V PostBootActionSequence.cpp:278 Reporting post-reboot status for statusType='OnlineMachineStatus'
      2018-07-20T14:19:21.6106921Z 08e8 S PostBootActionSequence.cpp:247 Entering `anonymous-namespace'::COnlineMachineStatusReport::operator ().
      2018-07-20T14:19:21.6106921Z 08e8 S StatusClient.cpp:109 Entering STDeployment::CStatusClient::ReportMachineStatusOnline.
      2018-07-20T14:19:24.7546146Z 08e8 S PostBootActionSequence.cpp:247 Leaving `anonymous-namespace'::COnlineMachineStatusReport::operator ().
      2018-07-20T14:19:24.7546146Z 08e8 S PostBootActionSequence.cpp:273 Leaving `anonymous-namespace'::ProcessStatusReport.
      2018-07-20T14:19:24.7546146Z 08e8 S PostBootActionSequence.cpp:560 Leaving STDeployment::CPostBootActionSequence::DispatchPostRebootTask.
      2018-07-20T14:19:24.7546146Z 08e8 I PostBootActionSequence.cpp:433 Command STATUS_REPORT completed
      2018-07-20T14:19:24.7546146Z 08e8 S PostBootActionSequence.cpp:415 Leaving STDeployment::CPostBootActionSequence::ProcessPostRebootTasksImpl.
      2018-07-20T14:19:24.7546146Z 08e8 S PostBootLauncher.cpp:32 Leaving STDeploy::CPostBootLauncher::LaunchPostBootActions.
      2018-07-20T14:19:24.7702420Z 08e8 V Sandbox.cpp:66 DoSandboxCleanup: Deleted file='C:\Windows\ProPatches\Patches\WinSCP-5.13.3-Setup.exe'
      2018-07-20T14:19:24.7702420Z 08e8 V Sandbox.cpp:66 DoSandboxCleanup: Deleted file='C:\Windows\ProPatches\Patches\AspNetWebToolsVS2013-KB4339279.exe'
      2018-07-20T14:19:24.7702420Z 08e8 V Sandbox.cpp:66 DoSandboxCleanup: Deleted file='C:\Windows\ProPatches\Patches\AspNetWebFrameworksToolsVS2013-KB4339279.exe'
      2018-07-20T14:19:24.7702420Z 08e8 V Sandbox.cpp:66 DoSandboxCleanup: Deleted file='C:\Windows\ProPatches\Patches\VMware-tools-10.3.0-8931395-x86_64.exe'
      2018-07-20T14:19:24.7702420Z 08e8 V Sandbox.cpp:66 DoSandboxCleanup: Deleted file='C:\Windows\ProPatches\Patches\Wireshark-win64-2.6.2.exe'
      2018-07-20T14:19:24.7702420Z 08e8 V Sandbox.cpp:66 DoSandboxCleanup: Deleted file='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\3c197e11-9480-484e-815a-1432a898bbe2.sequence.txt'
      2018-07-20T14:19:24.7702420Z 08e8 V Sandbox.cpp:66 DoSandboxCleanup: Deleted file='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\3c197e11-9480-484e-815a-1432a898bbe2.tracker'
      2018-07-20T14:19:24.7702420Z 08e8 V Sandbox.cpp:66 DoSandboxCleanup: Deleted file='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\7z.dll'
      2018-07-20T14:19:24.7702420Z 08e8 V Sandbox.cpp:66 DoSandboxCleanup: Deleted file='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\cl5.exe'
      2018-07-20T14:19:24.7702420Z 08e8 V Sandbox.cpp:66 DoSandboxCleanup: Deleted file='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\deployPackage-70992.zip'
      2018-07-20T14:19:24.7702420Z 08e8 I Sandbox.cpp:75 DoSandboxCleanup: Cannot delete filename='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\dplyevts.dll', file in use. Scheduled delete at next bootup.
      2018-07-20T14:19:24.7702420Z 08e8 V Sandbox.cpp:66 DoSandboxCleanup: Deleted file='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\InstallPatches-70992.bat'
      2018-07-20T14:19:24.7702420Z 08e8 V Sandbox.cpp:66 DoSandboxCleanup: Deleted file='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\PostBootTasks.xml'
      2018-07-20T14:19:24.7702420Z 08e8 V Sandbox.cpp:66 DoSandboxCleanup: Deleted file='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\safereboot.exe'
      2018-07-20T14:19:24.7702420Z 08e8 V Sandbox.cpp:66 DoSandboxCleanup: Deleted file='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\safereboot64.exe'
      2018-07-20T14:19:24.7702420Z 08e8 I Sandbox.cpp:75 DoSandboxCleanup: Cannot delete filename='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\stdeploy.exe', file in use. Scheduled delete at next bootup.
      2018-07-20T14:19:24.7702420Z 08e8 V Sandbox.cpp:66 DoSandboxCleanup: Deleted file='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\stdeploy.exe.config'
      2018-07-20T14:19:24.7858790Z 08e8 I Sandbox.cpp:75 DoSandboxCleanup: Cannot delete filename='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\STDeployerCore.dll', file in use. Scheduled delete at next bootup.
      2018-07-20T14:19:24.7858790Z 08e8 V Sandbox.cpp:66 DoSandboxCleanup: Deleted file='C:\Windows\ProPatches\Installation\InstallationSandbox#2018-07-20-T-02-11-19\ToDelete.txt'
      2018-07-20T14:19:24.7858790Z 08e8 S DeployExeStates.cpp:418 Leaving STDeploy::CRelaunchedExecutionPackageDeploy::DoStatefulRemediateActions.
      2018-07-20T14:19:24.7858790Z 08e8 I STDeploy.cpp:365 Current remediation phase completed. Process exit code: 0.
      2018-07-20T14:19:24.7858790Z 08e8 S STDeploy.cpp:257 Leaving wmain.

        • 1. Re: Server is installing patches 12 hours late
          brian.taylor.support SupportEmployee

          Hi Pete,

           

          It's difficult to say for sure without looking at all the other logging (namely STDeployerCore.log), but near as I can tell, the WinSCP patch you pushed likely timed out after 12 hours since it didn't return a response, and then the deployment moved onto the rest of the patches (the rest of which seem to not have had an issue).  We can dig in more deeply in a support case if you'd like, but that's what it looks like to me.  Does the WinSCP patch show as failed in your Deployment Tracker?  If so, that's almost certainly what happened.

           

          Let me know if that helps, or if you open a support case for this (I'll make sure I take that case).  I'm happy to help however I can.  One thing I would definitely recommend on machines like this is to schedule the reboot for a specific time of day - that way, if something like this does happen where one patch delays things unexpectedly, you won't reboot in the middle of the day, but overnight the next night or whatever you configure.

           

          Thanks,

          Brian