Post-installation publish not working - no obvious reason

[bunny-lajolla]

Hi,

I've managed to get the actual deployment part of this working, but when I select a post deploy option of "Publish after Deploy", it doesn't work. It actually seems to work, the log files indicate that my new item has been published, but it hasn't.

Here are the log file extracts. I am deploying one single (new) Sitecore item, a template field and then asking for a publish.

-- Log file extract showing installation and publish of 1 item
ManagedPoolThread #3 13:45:16 INFO Job started: Hhogdev.SitecorePackageDeployer.Tasks.InstallPackage
ManagedPoolThread #3 13:45:16 INFO Setting installer state to InstallingPackage
ManagedPoolThread #3 13:45:16 INFO Begin Installation: MasterItems.update
ManagedPoolThread #3 13:45:16 INFO Installing item '/sitecore/templates/User Defined/WwwAnglia/Supporting Content/Settings/Course Search Boosting/Wiggly'
ManagedPoolThread #2 13:45:16 INFO Job started: Index_Update_IndexName=sitecore_master_index
ManagedPoolThread #2 13:45:16 INFO Job ended: Index_Update_IndexName=sitecore_master_index (units processed: )
ManagedPoolThread #3 13:45:16 INFO Installing file '_DEV\DeployedItems.xml'
ManagedPoolThread #3 13:45:16 INFO Installing file 'bin\HedgehogDevelopment.SitecoreProject.PackageInstallPostProcessor.dll'
ManagedPoolThread #3 13:45:16 INFO Committing files.
ManagedPoolThread #3 13:45:16 INFO Copying files to be installed to solution and backing up overwritten files
6200 13:45:37 INFO Cache created: 'ExperienceAnalytics.Sites' (max size: 1MB, running total: 3224MB)
6268 13:45:38 INFO Path Analyzer Map EnsureAllDeployed started.
6268 13:45:38 INFO Path Analyzer Map EnsureAllDeployed complete.
ManagedPoolThread #5 13:45:39 INFO Job started: Index_Update_IndexName=sitecore_core_index
ManagedPoolThread #5 13:45:39 INFO Job ended: Index_Update_IndexName=sitecore_core_index (units processed: )
ManagedPoolThread #3 13:45:42 INFO Setting installer state to InstallingPostSteps
ManagedPoolThread #3 13:45:42 INFO Executing Post Installation instructions: 'HedgehogDevelopment.SitecoreProject.PackageInstallPostProcessor.DoPostDeployActions,HedgehogDevelopment.SitecoreProject.PackageInstallPostProcessor.dll'
ManagedPoolThread #3 13:45:42 INFO 05/08/2021-13:45:42: Starting PostDeployActions. Using assembly version 5.7.0.27
ManagedPoolThread #3 13:45:43 INFO 05/08/2021-13:45:43: Executing post deploy action HedgehogDevelopment.SitecoreProject.PackageInstallPostProcessor.BuiltIn.PublishDeployedItems, HedgehogDevelopment.SitecoreProject.PackageInstallPostProcessor with parameter 'Internet'
ManagedPoolThread #3 13:45:43 INFO 05/08/2021-13:45:43: Added item /sitecore/templates/User Defined/WwwAnglia/Supporting Content/Settings/Course Search Boosting/Wiggly to the publish queue
ManagedPoolThread #3 13:45:43 INFO 05/08/2021-13:45:43: Execution of post deploy action HedgehogDevelopment.SitecoreProject.PackageInstallPostProcessor.BuiltIn.PublishDeployedItems, HedgehogDevelopment.SitecoreProject.PackageInstallPostProcessor complete
ManagedPoolThread #3 13:45:43 WARN All caches have been cleared. This can decrease performance considerably. DEBUG entries have to be enabled in log4net to see the corresponding stack trace.
ManagedPoolThread #3 13:45:43 INFO 05/08/2021-13:45:43: TDS PostDeployActions complete.
ManagedPoolThread #3 13:45:43 INFO Installation Complete: MasterItems.update
ManagedPoolThread #3 13:45:43 INFO Setting installer state to InstallingPackage
ManagedPoolThread #3 13:45:43 INFO Setting installer state to Ready
ManagedPoolThread #3 13:45:43 INFO Job ended: Hhogdev.SitecorePackageDeployer.Tasks.InstallPackage (units processed: )
7692 13:45:43 INFO Job started: Publish
7148 13:45:43 INFO Job started: Publish to 'web'
7148 13:45:43 INFO HtmlCacheClearer clearing HTML caches for all sites (1).
7148 13:45:43 INFO HtmlCacheClearer done.
7148 13:45:43 INFO RenderingParametersCacheClearer clearing caches for the sites: authoring, preview, website.
7148 13:45:43 INFO RenderingParametersCacheClearer done.
7148 13:45:43 INFO Cache created: 'Analytics.ItemRecordsIDCache' (max size: 10MB, running total: 3234MB)
7148 13:45:43 INFO Started clearing all Glass.Mapper caches
7148 13:45:43 INFO Started clearing Glass.Mapper cache for context Default
7148 13:45:44 INFO Finished clearing Glass.Mapper cache for context Default
7148 13:45:44 INFO Finshed clearing all Glass.Mapper caches
7148 13:45:44 INFO Job ended: Publish to 'web' (units processed: 2)
-- End log file extract

-- Publishing log extract for the same time (Unsuccessful post-deployment auto-publish):
ManagedPoolThread #3 13:45:43 INFO [Publishing]: Starting to process 1 publishing options
ManagedPoolThread #3 13:45:43 INFO [PublishOptions]: root:{DDCE10C7-C0D1-4F26-81B4-A4862D5AEF27}, language:en, targets:Internet, database:web, mode:SingleItem, smart:True children:False, related:True
7148 13:45:43 WARN SingleItemPublish detected. PublishContext was overridden with MaxDegreeOfParallelism=1, DisableDatabaseCaches=False.
7148 13:45:43 INFO Starting [Publishing] - AddItemsToQueue
7148 13:45:43 INFO Finished [Publishing] - AddItemsToQueue in 0 ms
7148 13:45:43 INFO Starting [Publishing] - ProcessQueue
7148 13:45:43 INFO Finished [Publishing] - ProcessQueue in 18 ms
7148 13:45:43 INFO Publish Mode : SingleItem
7148 13:45:43 INFO Created : 0
7148 13:45:43 INFO Updated : 0
7148 13:45:43 INFO Deleted : 0
7148 13:45:43 INFO Skipped : 2
-- End publishing log extract

The last extract is a manual (and successful) publish by me of the new item, using the Content Editor. Notice the GUID is the same as above.

-- Publishing log extract for manual (successful) publish of the same item via Content Editor dialogs:
2540 13:53:05 INFO [Publishing]: Starting to process 1 publishing options
2540 13:53:05 INFO [PublishOptions]: root:{DDCE10C7-C0D1-4F26-81B4-A4862D5AEF27}, language:en, targets:Internet, database:web, mode:SingleItem, smart:True children:False, related:True
8152 13:53:06 WARN SingleItemPublish detected. PublishContext was overridden with MaxDegreeOfParallelism=1, DisableDatabaseCaches=False.
8152 13:53:06 INFO Starting [Publishing] - AddItemsToQueue
8152 13:53:06 INFO Finished [Publishing] - AddItemsToQueue in 0 ms
8152 13:53:06 INFO Starting [Publishing] - ProcessQueue
8152 13:53:06 INFO Finished [Publishing] - ProcessQueue in 209 ms
8152 13:53:06 INFO Publish Mode : SingleItem
8152 13:53:06 INFO Created : 1
8152 13:53:06 INFO Updated : 0
8152 13:53:06 INFO Deleted : 0
8152 13:53:06 INFO Skipped : 69
-- End publishing log extract

As you can see, the command appears to be identical but the outcome is different.
I am using TDS v5.7.0.27, Sitecore 9.0.2

Many thanks!