1. Scott Wells Avatar Scott Wells
  2. Illuminated Cloud
  3. IlluminatedCloud
  4. Issues

Delete Metadata dialog doesn't do anything.

Issue #2093 resolved
Justin Julicher created an issue

When I have components on a sandbox that I want to delete I open the ‘delete metadata' dialog and select the ‘Server Only’ option, select the metadata and then click 'Delete’ and nothing happens.

‌

In the above screen shot I select the metadata then click ‘Delete’ and nothing happens - no notification or message or anything. In the log I just get:

‌

2022-04-27 09:04:23,213 [603281114]   INFO - c.m.a.p.o.BuildStatusLookupOperation - Returning results.
2022-04-27 09:06:15,635 [603393536]   INFO - #c.i.i.b.DeleteFromServerAction - Deleting metadata from requested connection jjdev.
2022-04-27 09:06:15,635 [603393536]   WARN - #c.i.i.b.ForceComMetadataDeleter - Nothing to deploy.
2022-04-27 09:06:19,946 [603397847]   INFO - #c.i.i.a.RevealFileAction - Exit code 1

‌

Any ideas?

Comments (19)

  4. Scott Wells repo owner

    Justin, when I open the attached file in 7Zip I'm not seeing any contents. Can you reattach?

  7. Justin Julicher reporter

    You should see the command from 2022-05-06 15:39:34,468 in the log file - although there isn’t much to see …

  8. Scott Wells repo owner

    Thanks. And that's with debug logging enabled? I would expect to see at least some entries like the following:

    2022-05-06 08:18:24,408 [53005632]   INFO - #c.i.i.b.DeleteFromServerAction - Deleting metadata from requested connection <connection>.
2022-05-06 08:18:24,408 [53005632]   FINE - #c.i.i.b.DeleteFromServerAction - Deleting from connection <connection>: { MetadataType[metadataName], ... }.
2022-05-06 08:18:24,424 [53005648]   FINE - #c.i.i.b.ForceComMetadataDeleter - Clearing build failures for all files requested for deployment.
2022-05-06 08:18:24,424 [53005648]   FINE - #c.i.i.b.ForceComMetadataDeleter - Deleting metadataName, ... from <connection>

    If debug logging was enabled, it looks like I need to instrument a bit more. If it wasn't, please enable it and send over a new log.

  9. Justin Julicher reporter

    This is the debug logging I had enabled:

    #com.illuminatedcloud.intellij.builder.DeleteFromServerAction
    #com.illuminatedcloud.intellij.builder.ForceComBuilder
    #com.illuminatedcloud.intellij.builder.ForceComBuilderUtil
    #com.illuminatedcloud.intellij.builder.ForceComMetadataDeleter
    #com.illuminatedcloud.intellij.builder.ForceComSfdxDeployer
    #com.illuminatedcloud.intellij.builder.ForceComSfdxMetadataUtil
    #com.illuminatedcloud.util.VariableLengthPollingInterval
    #com.illuminatedcloud.util.CommandLineUtil
    #com.illuminatedcloud.intellij.sfdx.SfdxUtil
    #com.illuminatedcloud.intellij.builder.ForceComSfdxDeployer
    #com.illuminatedcloud.intellij.builder.ForceComSfdxRetriever
    #com.illuminatedcloud.intellij.builder.ForceComSfdxMetadataUtil
    #com.illuminatedcloud.util.VariableLengthPollingInterval

    I’ll try again and see what I get….

  10. Justin Julicher reporter

    This is what I got (just shortened the output from when I opened the delete dialog:

    ‌

    2022-05-10 15:49:34,831 [19864897]   INFO - #c.i.c.CloudConfigManager - === Start.updateConnection ===
2022-05-10 15:49:34,832 [19864898]   INFO - #c.i.c.CloudConfigManager - === doConnect ===
2022-05-10 15:49:34,832 [19864898]   INFO - #c.i.c.CloudConfigManager - === getConnectionState ===
2022-05-10 15:49:43,719 [19873785]   FINE - #c.i.i.s.SfdxUtil - Using SFDX project file C:/Users/justinj/Workspace/RealEstateSystemsSalesforce2/sfdx-project.json for module RealEstateSystemsSalesforce2.
2022-05-10 15:49:43,719 [19873785]   FINE - #c.i.i.s.SfdxUtil - Using SFDX project file C:/Users/justinj/Workspace/RealEstateSystemsSalesforce2/sfdx-project.json for module RealEstateSystemsSalesforce2.
2022-05-10 15:49:43,719 [19873785]   FINE - #c.i.i.s.SfdxUtil - Loaded SFDX project from file C:/Users/justinj/Workspace/RealEstateSystemsSalesforce2/sfdx-project.json.
2022-05-10 15:49:43,719 [19873785]   FINE - #c.i.i.s.SfdxUtil - Loaded SFDX project from file C:/Users/justinj/Workspace/RealEstateSystemsSalesforce2/sfdx-project.json.
2022-05-10 15:49:56,188 [19886254]   FINE - #c.i.i.s.SfdxUtil - Using SFDX project file C:/Users/justinj/Workspace/RealEstateSystemsSalesforce/sfdx-project.json for module RealEstateSystemsSalesforce.
2022-05-10 15:49:56,188 [19886254]   FINE - #c.i.i.s.SfdxUtil - Using SFDX project file C:/Users/justinj/Workspace/RealEstateSystemsSalesforce/sfdx-project.json for module RealEstateSystemsSalesforce.
2022-05-10 15:49:56,189 [19886255]   FINE - #c.i.i.s.SfdxUtil - Loaded SFDX project from file C:/Users/justinj/Workspace/RealEstateSystemsSalesforce/sfdx-project.json.
2022-05-10 15:49:56,189 [19886255]   FINE - #c.i.i.s.SfdxUtil - Loaded SFDX project from file C:/Users/justinj/Workspace/RealEstateSystemsSalesforce/sfdx-project.json.
2022-05-10 15:49:56,365 [19886431]   INFO - #c.i.c.ComponentStoreImpl - Saving appIlluminatedCloudSettings took 16 ms, TerminalOptionsProvider took 16 ms, WebTypesNpmLoader took 15 ms
2022-05-10 15:49:56,434 [19886500]   INFO - #c.i.c.ComponentStoreImpl - Saving Project(name=RealEstateSystemsSalesforce, containerState=COMPONENT_CREATED, componentStore=C:\Users\justinj\Workspace\RealEstateSystemsSalesforce)FileEditorManager took 15 ms, NodePackageJsonFileManager took 14 ms
2022-05-10 15:49:56,450 [19886516]   INFO - #c.i.c.ComponentStoreImpl - Saving Project(name=QIC_Utils, containerState=COMPONENT_CREATED, componentStore=C:\Users\justinj\Workspace\QIC_Utils)IlluminatedCloudSettings took 16 ms
2022-05-10 15:49:56,466 [19886532]   INFO - #c.i.c.ComponentStoreImpl - Saving Project(name=RealEstateSystemsSalesforce2, containerState=COMPONENT_CREATED, componentStore=C:\Users\justinj\Workspace\RealEstateSystemsSalesforce2)IlluminatedCloudSettings took 16 ms
2022-05-10 15:50:59,059 [19949125]   FINE - #c.i.i.b.ForceComBuilder - Initializing the Salesforce API client for connection jjdev2
2022-05-10 15:50:59,059 [19949125]   INFO - #c.i.c.ForceComApiClient - Logging into Salesforce DX as j.julicher@qic.com.jjdev2.
2022-05-10 15:50:59,059 [19949125]   INFO - #c.i.c.ForceComApiClient - Retrieving org details from Salesforce CLI.
2022-05-10 15:50:59,059 [19949125]   INFO - o.a.c.w.s.f.ReflectionServiceFactoryBean - Creating Service {urn:partner.soap.sforce.com}PartnerService from class com.sforce.soap.partner.PartnerApi
2022-05-10 15:50:59,075 [19949141]   INFO - o.a.c.w.s.f.ReflectionServiceFactoryBean - Creating Service {http://soap.sforce.com/2006/04/metadata}MetadataService from class com.sforce.soap.metadata.MetadataApi
2022-05-10 15:50:59,075 [19949141]   INFO - o.a.c.w.s.f.ReflectionServiceFactoryBean - Creating Service {http://soap.sforce.com/2006/08/apex}ApexService from class com.sforce.soap.apex.ApexApi
2022-05-10 15:50:59,075 [19949141]   INFO - o.a.c.w.s.f.ReflectionServiceFactoryBean - Creating Service {urn:tooling.soap.sforce.com}ToolingService from class com.sforce.soap.tooling.ToolingApi
2022-05-10 15:50:59,447 [19949513]   INFO - #c.i.c.ForceComApiClient - Logging into Salesforce DX as j.julicher@qic.com.jjdev2.
2022-05-10 15:50:59,447 [19949513]   INFO - #c.i.c.ForceComApiClient - Retrieving org details from Salesforce CLI.
2022-05-10 15:50:59,447 [19949513]   INFO - #c.i.c.ForceComApiClient - Retrieving org details from Salesforce CLI.
2022-05-10 15:50:59,447 [19949513]   INFO - #c.i.c.ForceComApiClient - Retrieving org details for jjdev2 from Salesforce CLI.
2022-05-10 15:50:59,447 [19949513]   FINE - #c.i.u.CommandLineUtil - Running command line: 'C:/Program Files/npm/sfdx.cmd force:org:display -u j.julicher@qic.com.jjdev2 --json' from working directory: 'C:\Program Files\npm'.
2022-05-10 15:50:59,463 [19949529]   FINE - #c.i.u.VariableLengthPollingInterval - C:/Program Files/npm/sfdx.cmd force:org:display -u j.julicher@qic.com.jjdev2 --json: Using polling interval 1000 ms for polling iteration 1.
2022-05-10 15:51:00,463 [19950529]   FINE - #c.i.u.VariableLengthPollingInterval - C:/Program Files/npm/sfdx.cmd force:org:display -u j.julicher@qic.com.jjdev2 --json: Using polling interval 1000 ms for polling iteration 2.
2022-05-10 15:51:01,464 [19951530]   FINE - #c.i.u.VariableLengthPollingInterval - C:/Program Files/npm/sfdx.cmd force:org:display -u j.julicher@qic.com.jjdev2 --json: Using polling interval 1000 ms for polling iteration 3.
2022-05-10 15:51:02,464 [19952530]   FINE - #c.i.u.VariableLengthPollingInterval - C:/Program Files/npm/sfdx.cmd force:org:display -u j.julicher@qic.com.jjdev2 --json: Using polling interval 1000 ms for polling iteration 4.
2022-05-10 15:51:03,464 [19953530]   FINE - #c.i.u.VariableLengthPollingInterval - C:/Program Files/npm/sfdx.cmd force:org:display -u j.julicher@qic.com.jjdev2 --json: Using polling interval 1000 ms for polling iteration 5.
2022-05-10 15:51:04,465 [19954531]   FINE - #c.i.u.CommandLineUtil - Returning command-line response: CommandLineResponse{exitCode=0, output='{  "status": 0,  "result": {    "id": "00DBn000000SwNdMAK",    "accessToken": "00DBn000000SwNd!AQEAQHW36BoNPBftGQkAVgxF7ehy5ODPRrtbA86ql0unlZBFChRnFXfJQnFpvnZr1FpY2XnNX6sgSDwhhsLFjQDSUwavPrAJ",    "instanceUrl": "https://qicgre--jjdev2.my.salesforce.com",    "username": "j.julicher@qic.com.jjdev2",    "clientId": "PlatformCLI",    "connectedStatus": "Connected",    "alias": "jjdev2"  },  "warnings": [    "This command will expose sensitive information that allows for subsequent activity using your current authenticated session.\nSharing this information is equivalent to logging someone in under the current credential, resulting in unintended access and escalation of privilege.\nFor additional information, please review the authorization section of the https://developer.salesforce.com/docs/atlas.en-us.234.0.sfdx_dev.meta/sfdx_dev/sfdx_dev_auth_web_flow.htm"  ]}', error='', duration=5018}
2022-05-10 15:51:04,465 [19954531]   INFO - o.a.c.w.s.f.ReflectionServiceFactoryBean - Creating Service {urn:partner.soap.sforce.com}PartnerService from class com.sforce.soap.partner.PartnerApi
2022-05-10 15:51:04,480 [19954546]   INFO - o.a.c.w.s.f.ReflectionServiceFactoryBean - Creating Service {http://soap.sforce.com/2006/04/metadata}MetadataService from class com.sforce.soap.metadata.MetadataApi
2022-05-10 15:51:04,480 [19954546]   INFO - o.a.c.w.s.f.ReflectionServiceFactoryBean - Creating Service {http://soap.sforce.com/2006/08/apex}ApexService from class com.sforce.soap.apex.ApexApi
2022-05-10 15:51:04,480 [19954546]   INFO - o.a.c.w.s.f.ReflectionServiceFactoryBean - Creating Service {urn:tooling.soap.sforce.com}ToolingService from class com.sforce.soap.tooling.ToolingApi
2022-05-10 15:51:05,893 [19955959]   FINE - #c.i.i.b.ForceComBuilder - Creating a metadata package for module RealEstateSystemsSalesforce2.
2022-05-10 15:51:05,893 [19955959]   FINE - #c.i.i.b.ForceComBuilder -   Creating a metadata package based on package.xml file manifest/package.xml.
2022-05-10 15:51:12,108 [19962174]   FINE - #c.i.i.b.ForceComBuilder - Creating a metadata package for module RealEstateSystemsSalesforce2.
2022-05-10 15:51:12,108 [19962174]   FINE - #c.i.i.b.ForceComBuilder -   Creating a metadata package based on package.xml file manifest/package.xml.
2022-05-10 15:51:23,523 [19973589]   INFO - #c.i.i.b.DeleteFromServerAction - Deleting metadata from requested connection jjdev2.
2022-05-10 15:51:23,523 [19973589]   FINE - #c.i.i.b.DeleteFromServerAction - Deleting from connection jjdev2: { ApexClass[AmendmentsToBeApprovedController], CustomNotificationType[NotifyOwnerWhenLeaseProposalApproved] }.
2022-05-10 15:51:23,523 [19973589]   WARN - #c.i.i.b.ForceComMetadataDeleter - Nothing to deploy.
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR - com.intellij.openapi.progress.ProcessCanceledException
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.openapi.progress.EmptyProgressIndicatorBase.checkCanceled(EmptyProgressIndicatorBase.java:57)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.doCheckCanceled(CoreProgressManager.java:144)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.openapi.progress.ProgressManager.checkCanceled(ProgressManager.java:211)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.extapi.psi.StubBasedPsiElementBase.isValid(StubBasedPsiElementBase.java:256)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPsiElementPointerImpl.getElement(SmartPsiElementPointerImpl.java:78)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPointerManagerImpl.getCachedPointer(SmartPointerManagerImpl.java:119)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPointerManagerImpl.createSmartPsiElementPointer(SmartPointerManagerImpl.java:84)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPointerManagerImpl.createSmartPsiElementPointer(SmartPointerManagerImpl.java:74)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPointerManagerImpl.createSmartPsiElementPointer(SmartPointerManagerImpl.java:69)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.psi.SmartPointerManager.createPointer(SmartPointerManager.java:32)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.codeInsight.hints.CodeAuthorClickHandler.<init>(VcsCodeVisionProvider.kt:156)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.codeInsight.hints.VcsCodeVisionProvider$computeCodeVision$$inlined$runReadAction$1.compute(actions.kt:83)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.openapi.application.impl.ApplicationImpl.runReadAction(ApplicationImpl.java:920)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.codeInsight.hints.VcsCodeVisionProvider.computeCodeVision(VcsCodeVisionProvider.kt:269)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.codeInsight.hints.VcsCodeVisionProvider.computeCodeVision(VcsCodeVisionProvider.kt:42)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$calculateFrontendLenses$1.invoke(CodeVisionHost.kt:404)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$calculateFrontendLenses$1.invoke(CodeVisionHost.kt:61)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$sam$java_lang_Runnable$0.run(CodeVisionHost.kt)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.lambda$runProcess$2(CoreProgressManager.java:189)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.lambda$executeProcessUnderProgress$12(CoreProgressManager.java:608)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.registerIndicatorAndRun(CoreProgressManager.java:683)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.computeUnderProgress(CoreProgressManager.java:639)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.executeProcessUnderProgress(CoreProgressManager.java:607)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.openapi.progress.impl.ProgressManagerImpl.executeProcessUnderProgress(ProgressManagerImpl.java:60)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.runProcess(CoreProgressManager.java:176)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$executeOnPooledThread$1.run(CodeVisionHost.kt:457)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1736)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at java.base/java.util.concurrent.Executors$PrivilegedThreadFactory$1$1.run(Executors.java:668)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at java.base/java.util.concurrent.Executors$PrivilegedThreadFactory$1$1.run(Executors.java:665)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at java.base/java.security.AccessController.doPrivileged(Native Method)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at java.base/java.util.concurrent.Executors$PrivilegedThreadFactory$1.run(Executors.java:665)
2022-05-10 15:51:23,545 [19973611]   INFO - STDERR -    at java.base/java.lang.Thread.run(Thread.java:829)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR - com.intellij.openapi.progress.ProcessCanceledException
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.EmptyProgressIndicatorBase.checkCanceled(EmptyProgressIndicatorBase.java:57)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.doCheckCanceled(CoreProgressManager.java:144)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.ProgressManager.checkCanceled(ProgressManager.java:211)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.extapi.psi.StubBasedPsiElementBase.isValid(StubBasedPsiElementBase.java:256)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPsiElementPointerImpl.getElement(SmartPsiElementPointerImpl.java:78)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPointerManagerImpl.getCachedPointer(SmartPointerManagerImpl.java:119)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPointerManagerImpl.createSmartPsiElementPointer(SmartPointerManagerImpl.java:84)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPointerManagerImpl.createSmartPsiElementPointer(SmartPointerManagerImpl.java:74)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPointerManagerImpl.createSmartPsiElementPointer(SmartPointerManagerImpl.java:69)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.psi.SmartPointerManager.createPointer(SmartPointerManager.java:32)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.codeInsight.hints.CodeAuthorClickHandler.<init>(VcsCodeVisionProvider.kt:156)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.codeInsight.hints.VcsCodeVisionProvider$computeCodeVision$$inlined$runReadAction$1.compute(actions.kt:83)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.application.impl.ApplicationImpl.runReadAction(ApplicationImpl.java:920)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.codeInsight.hints.VcsCodeVisionProvider.computeCodeVision(VcsCodeVisionProvider.kt:269)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.codeInsight.hints.VcsCodeVisionProvider.computeCodeVision(VcsCodeVisionProvider.kt:42)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$calculateFrontendLenses$1.invoke(CodeVisionHost.kt:404)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$calculateFrontendLenses$1.invoke(CodeVisionHost.kt:61)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$sam$java_lang_Runnable$0.run(CodeVisionHost.kt)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.lambda$runProcess$2(CoreProgressManager.java:189)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.lambda$executeProcessUnderProgress$12(CoreProgressManager.java:608)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.registerIndicatorAndRun(CoreProgressManager.java:683)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.computeUnderProgress(CoreProgressManager.java:639)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.executeProcessUnderProgress(CoreProgressManager.java:607)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.impl.ProgressManagerImpl.executeProcessUnderProgress(ProgressManagerImpl.java:60)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.runProcess(CoreProgressManager.java:176)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$executeOnPooledThread$1.run(CodeVisionHost.kt:457)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1736)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at java.base/java.util.concurrent.Executors$PrivilegedThreadFactory$1$1.run(Executors.java:668)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at java.base/java.util.concurrent.Executors$PrivilegedThreadFactory$1$1.run(Executors.java:665)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at java.base/java.security.AccessController.doPrivileged(Native Method)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at java.base/java.util.concurrent.Executors$PrivilegedThreadFactory$1.run(Executors.java:665)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at java.base/java.lang.Thread.run(Thread.java:829)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR - com.intellij.openapi.progress.ProcessCanceledException
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.EmptyProgressIndicatorBase.checkCanceled(EmptyProgressIndicatorBase.java:57)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.doCheckCanceled(CoreProgressManager.java:144)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.ProgressManager.checkCanceled(ProgressManager.java:211)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.ProgressIndicatorProvider.checkCanceled(ProgressIndicatorProvider.java:23)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.psi.impl.source.tree.CompositeElement.getPsi(CompositeElement.java:687)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.psi.impl.source.SourceTreeToPsiMap.treeElementToPsi(SourceTreeToPsiMap.java:16)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.psi.impl.source.tree.SharedImplUtil.getFirstChild(SharedImplUtil.java:37)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.extapi.psi.ASTDelegatePsiElement.getFirstChild(ASTDelegatePsiElement.java:99)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.psi.SyntaxTraverser$PsiApi.first(SyntaxTraverser.java:296)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.psi.SyntaxTraverser$PsiApi.first(SyntaxTraverser.java:264)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.psi.SyntaxTraverser$ApiEx.children(SyntaxTraverser.java:241)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.codeInsight.hints.InlayHintsUtils.getTextRangeWithoutLeadingCommentsAndWhitespaces(InlayHintsUtils.kt:279)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.codeInsight.hints.VcsCodeVisionProvider$computeCodeVision$$inlined$runReadAction$1.compute(actions.kt:77)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.application.impl.ApplicationImpl.runReadAction(ApplicationImpl.java:920)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.codeInsight.hints.VcsCodeVisionProvider.computeCodeVision(VcsCodeVisionProvider.kt:269)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.codeInsight.hints.VcsCodeVisionProvider.computeCodeVision(VcsCodeVisionProvider.kt:42)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$calculateFrontendLenses$1.invoke(CodeVisionHost.kt:404)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$calculateFrontendLenses$1.invoke(CodeVisionHost.kt:61)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$sam$java_lang_Runnable$0.run(CodeVisionHost.kt)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.lambda$runProcess$2(CoreProgressManager.java:189)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.lambda$executeProcessUnderProgress$12(CoreProgressManager.java:608)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.registerIndicatorAndRun(CoreProgressManager.java:683)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.computeUnderProgress(CoreProgressManager.java:639)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.executeProcessUnderProgress(CoreProgressManager.java:607)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.impl.ProgressManagerImpl.executeProcessUnderProgress(ProgressManagerImpl.java:60)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.runProcess(CoreProgressManager.java:176)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$executeOnPooledThread$1.run(CodeVisionHost.kt:457)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1736)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at java.base/java.util.concurrent.Executors$PrivilegedThreadFactory$1$1.run(Executors.java:668)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at java.base/java.util.concurrent.Executors$PrivilegedThreadFactory$1$1.run(Executors.java:665)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at java.base/java.security.AccessController.doPrivileged(Native Method)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at java.base/java.util.concurrent.Executors$PrivilegedThreadFactory$1.run(Executors.java:665)
2022-05-10 15:51:23,561 [19973627]   INFO - STDERR -    at java.base/java.lang.Thread.run(Thread.java:829)
2022-05-10 15:51:23,869 [19973935]   FINE - #c.i.i.s.SfdxUtil - Using SFDX project file C:/Users/justinj/Workspace/QIC_Utils/sfdx-project.json for module QIC_Utils.
2022-05-10 15:51:23,869 [19973935]   FINE - #c.i.i.s.SfdxUtil - Loaded SFDX project from file C:/Users/justinj/Workspace/QIC_Utils/sfdx-project.json.
2022-05-10 15:51:28,103 [19978169]   INFO - #c.i.i.a.RevealFileAction - Exit code 1
2022-05-10 15:51:28,708 [19978774]   FINE - #c.i.i.s.SfdxUtil - Using SFDX project file C:/Users/justinj/Workspace/RealEstateSystemsSalesforce/sfdx-project.json for module RealEstateSystemsSalesforce.
2022-05-10 15:51:28,708 [19978774]   FINE - #c.i.i.s.SfdxUtil - Using SFDX project file C:/Users/justinj/Workspace/RealEstateSystemsSalesforce/sfdx-project.json for module RealEstateSystemsSalesforce.
2022-05-10 15:51:28,709 [19978775]   FINE - #c.i.i.s.SfdxUtil - Loaded SFDX project from file C:/Users/justinj/Workspace/RealEstateSystemsSalesforce/sfdx-project.json.

    ‌

  11. Scott Wells repo owner

    Justin, can you enable debug logging for metadata deployment? Right now your list just includes CLI-based metadata deployment, but the deployment of destructive changes uses the Metadata API directly. That should help to fill in the following gap in the provided logs:

    2022-05-10 15:51:23,523 [19973589]   INFO - #c.i.i.b.DeleteFromServerAction - Deleting metadata from requested connection jjdev2.
2022-05-10 15:51:23,523 [19973589]   FINE - #c.i.i.b.DeleteFromServerAction - Deleting from connection jjdev2: { ApexClass[AmendmentsToBeApprovedController], CustomNotificationType[NotifyOwnerWhenLeaseProposalApproved] }.
... Should be more details here when deployment logging is enabled
2022-05-10 15:51:23,523 [19973589]   WARN - #c.i.i.b.ForceComMetadataDeleter - Nothing to deploy.
  12. Justin Julicher reporter

    Hi Scott - this is what I got by adding the deployment debugging on:

    ‌

    2022-05-18 09:27:10,903 [86167349]   INFO - c.m.a.p.i.c.u.VcsHelper - getRepositoryContext: cache hit: C:/Users/justinj/Workspace/RealEstateSystemsSalesforce
2022-05-18 09:27:11,406 [86167852]   INFO - c.m.a.p.i.c.u.VcsHelper - getRepositoryContext: cache hit: C:/Users/justinj/Workspace/RealEstateSystemsSalesforce
2022-05-18 09:27:13,425 [86169871]   FINE - #c.i.i.b.ForceComBuilder - Creating a metadata package for module RealEstateSystemsSalesforce2.
2022-05-18 09:27:13,425 [86169871]   FINE - #c.i.i.b.ForceComBuilder -   Creating a metadata package based on package.xml file manifest/package.xml.
2022-05-18 09:27:19,109 [86175555]   FINE - #c.i.i.b.ForceComBuilder - Creating a metadata package for module RealEstateSystemsSalesforce2.
2022-05-18 09:27:19,109 [86175555]   FINE - #c.i.i.b.ForceComBuilder -   Creating a metadata package based on package.xml file manifest/package.xml.
2022-05-18 09:27:23,491 [86179937]   INFO - #c.i.i.b.DeleteFromServerAction - Deleting metadata from requested connection jjdev2.
2022-05-18 09:27:23,492 [86179938]   FINE - #c.i.i.b.DeleteFromServerAction - Deleting from connection jjdev2: { ApexClass[AmendmentsToBeApprovedController], CustomNotificationType[NotifyOwnerWhenLeaseProposalApproved] }.
2022-05-18 09:27:23,492 [86179938]   WARN - #c.i.i.b.ForceComMetadataDeleter - Nothing to deploy.
2022-05-18 09:27:23,508 [86179954]   INFO - STDERR - com.intellij.openapi.progress.ProcessCanceledException
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.openapi.progress.EmptyProgressIndicatorBase.checkCanceled(EmptyProgressIndicatorBase.java:57)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.doCheckCanceled(CoreProgressManager.java:144)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.openapi.progress.ProgressManager.checkCanceled(ProgressManager.java:211)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.extapi.psi.StubBasedPsiElementBase.isValid(StubBasedPsiElementBase.java:256)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPsiElementPointerImpl.getElement(SmartPsiElementPointerImpl.java:78)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPointerManagerImpl.getCachedPointer(SmartPointerManagerImpl.java:119)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPointerManagerImpl.createSmartPsiElementPointer(SmartPointerManagerImpl.java:84)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPointerManagerImpl.createSmartPsiElementPointer(SmartPointerManagerImpl.java:74)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPointerManagerImpl.createSmartPsiElementPointer(SmartPointerManagerImpl.java:69)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.psi.SmartPointerManager.createPointer(SmartPointerManager.java:32)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.codeInsight.hints.CodeAuthorClickHandler.<init>(VcsCodeVisionProvider.kt:156)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.codeInsight.hints.VcsCodeVisionProvider$computeCodeVision$$inlined$runReadAction$1.compute(actions.kt:83)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.openapi.application.impl.ApplicationImpl.runReadAction(ApplicationImpl.java:920)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.codeInsight.hints.VcsCodeVisionProvider.computeCodeVision(VcsCodeVisionProvider.kt:269)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.codeInsight.hints.VcsCodeVisionProvider.computeCodeVision(VcsCodeVisionProvider.kt:42)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$calculateFrontendLenses$1.invoke(CodeVisionHost.kt:404)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$calculateFrontendLenses$1.invoke(CodeVisionHost.kt:61)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$sam$java_lang_Runnable$0.run(CodeVisionHost.kt)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.lambda$runProcess$2(CoreProgressManager.java:189)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.lambda$executeProcessUnderProgress$12(CoreProgressManager.java:608)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.registerIndicatorAndRun(CoreProgressManager.java:683)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.computeUnderProgress(CoreProgressManager.java:639)
2022-05-18 09:27:23,509 [86179955]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.executeProcessUnderProgress(CoreProgressManager.java:607)
2022-05-18 09:27:23,510 [86179956]   INFO - STDERR -    at com.intellij.openapi.progress.impl.ProgressManagerImpl.executeProcessUnderProgress(ProgressManagerImpl.java:60)
2022-05-18 09:27:23,510 [86179956]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.runProcess(CoreProgressManager.java:176)
2022-05-18 09:27:23,510 [86179956]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$executeOnPooledThread$1.run(CodeVisionHost.kt:457)
2022-05-18 09:27:23,510 [86179956]   INFO - STDERR -    at java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1736)
2022-05-18 09:27:23,510 [86179956]   INFO - STDERR -    at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
2022-05-18 09:27:23,510 [86179956]   INFO - STDERR -    at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
2022-05-18 09:27:23,510 [86179956]   INFO - STDERR -    at java.base/java.util.concurrent.Executors$PrivilegedThreadFactory$1$1.run(Executors.java:668)
2022-05-18 09:27:23,510 [86179956]   INFO - STDERR -    at java.base/java.util.concurrent.Executors$PrivilegedThreadFactory$1$1.run(Executors.java:665)
2022-05-18 09:27:23,510 [86179956]   INFO - STDERR -    at java.base/java.security.AccessController.doPrivileged(Native Method)
2022-05-18 09:27:23,510 [86179956]   INFO - STDERR -    at java.base/java.util.concurrent.Executors$PrivilegedThreadFactory$1.run(Executors.java:665)
2022-05-18 09:27:23,510 [86179956]   INFO - STDERR -    at java.base/java.lang.Thread.run(Thread.java:829)
2022-05-18 09:27:23,510 [86179956]   INFO - STDERR - com.intellij.openapi.progress.ProcessCanceledException
2022-05-18 09:27:23,510 [86179956]   INFO - STDERR -    at com.intellij.openapi.progress.EmptyProgressIndicatorBase.checkCanceled(EmptyProgressIndicatorBase.java:57)
2022-05-18 09:27:23,510 [86179956]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.doCheckCanceled(CoreProgressManager.java:144)
2022-05-18 09:27:23,510 [86179956]   INFO - STDERR -    at com.intellij.openapi.progress.ProgressManager.checkCanceled(ProgressManager.java:211)
2022-05-18 09:27:23,510 [86179956]   INFO - STDERR -    at com.intellij.extapi.psi.StubBasedPsiElementBase.isValid(StubBasedPsiElementBase.java:256)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPsiElementPointerImpl.getElement(SmartPsiElementPointerImpl.java:78)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPointerManagerImpl.getCachedPointer(SmartPointerManagerImpl.java:119)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPointerManagerImpl.createSmartPsiElementPointer(SmartPointerManagerImpl.java:84)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPointerManagerImpl.createSmartPsiElementPointer(SmartPointerManagerImpl.java:74)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.psi.impl.smartPointers.SmartPointerManagerImpl.createSmartPsiElementPointer(SmartPointerManagerImpl.java:69)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.psi.SmartPointerManager.createPointer(SmartPointerManager.java:32)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.codeInsight.hints.CodeAuthorClickHandler.<init>(VcsCodeVisionProvider.kt:156)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.codeInsight.hints.VcsCodeVisionProvider$computeCodeVision$$inlined$runReadAction$1.compute(actions.kt:83)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.openapi.application.impl.ApplicationImpl.runReadAction(ApplicationImpl.java:920)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.codeInsight.hints.VcsCodeVisionProvider.computeCodeVision(VcsCodeVisionProvider.kt:269)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.codeInsight.hints.VcsCodeVisionProvider.computeCodeVision(VcsCodeVisionProvider.kt:42)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$calculateFrontendLenses$1.invoke(CodeVisionHost.kt:404)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$calculateFrontendLenses$1.invoke(CodeVisionHost.kt:61)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$sam$java_lang_Runnable$0.run(CodeVisionHost.kt)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.lambda$runProcess$2(CoreProgressManager.java:189)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.lambda$executeProcessUnderProgress$12(CoreProgressManager.java:608)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.registerIndicatorAndRun(CoreProgressManager.java:683)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.computeUnderProgress(CoreProgressManager.java:639)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.executeProcessUnderProgress(CoreProgressManager.java:607)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.openapi.progress.impl.ProgressManagerImpl.executeProcessUnderProgress(ProgressManagerImpl.java:60)
2022-05-18 09:27:23,511 [86179957]   INFO - STDERR -    at com.intellij.openapi.progress.impl.CoreProgressManager.runProcess(CoreProgressManager.java:176)
2022-05-18 09:27:23,512 [86179958]   INFO - STDERR -    at com.intellij.codeInsight.codeVision.CodeVisionHost$executeOnPooledThread$1.run(CodeVisionHost.kt:457)
2022-05-18 09:27:23,512 [86179958]   INFO - STDERR -    at java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1736)
2022-05-18 09:27:23,512 [86179958]   INFO - STDERR -    at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
2022-05-18 09:27:23,512 [86179958]   INFO - STDERR -    at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
2022-05-18 09:27:23,512 [86179958]   INFO - STDERR -    at java.base/java.util.concurrent.Executors$PrivilegedThreadFactory$1$1.run(Executors.java:668)
2022-05-18 09:27:23,512 [86179958]   INFO - STDERR -    at java.base/java.util.concurrent.Executors$PrivilegedThreadFactory$1$1.run(Executors.java:665)
2022-05-18 09:27:23,512 [86179958]   INFO - STDERR -    at java.base/java.security.AccessController.doPrivileged(Native Method)
2022-05-18 09:27:23,512 [86179958]   INFO - STDERR -    at java.base/java.util.concurrent.Executors$PrivilegedThreadFactory$1.run(Executors.java:665)
2022-05-18 09:27:23,512 [86179958]   INFO - STDERR -    at java.base/java.lang.Thread.run(Thread.java:829)
2022-05-18 09:27:23,713 [86180159]   INFO - c.m.a.p.i.c.u.VcsHelper - getRepositoryContext: cache hit: C:/Users/justinj/Workspace/RealEstateSystemsSalesforce
2022-05-18 09:27:24,169 [86180615]   INFO - c.m.a.p.i.c.u.VcsHelper - getRepositoryContext: cache hit: C:/Users/justinj/Workspace/RealEstateSystemsSalesforce
2022-05-18 09:27:26,434 [86182880]   INFO - c.m.a.p.i.c.u.VcsHelper - getRepositoryContext: cache hit: C:/Users/justinj/Workspace/RealEstateSystemsSalesforce
2022-05-18 09:27:26,729 [86183175]   INFO - c.m.a.p.i.c.u.VcsHelper - getRepositoryContext: cache hit: C:/Users/justinj/Workspace/RealEstateSystemsSalesforce
2022-05-18 09:27:28,184 [86184630]   INFO - #c.i.c.ComponentStoreImpl - Saving appEditorColorsManagerImpl took 12 ms
2022-05-18 09:27:28,230 [86184676]   INFO - c.m.a.p.i.c.u.VcsHelper - getRepositoryContext: cache hit: C:/Users/justinj/Workspace/RealEstateSystemsSalesforce
2022-05-18 09:27:28,468 [86184914]   FINE - #c.i.i.b.ForceComBuildFailureAnnotator - Found compile failures for ParentAction__c.field-meta.xml
2022-05-18 09:27:28,469 [86184915]   FINE - #c.i.i.b.ForceComBuildFailureAnnotator - Adding external annotation:
  Problem type    = ERROR
  Problem message = An unexpected error occurred. Please include this ErrorId if you contact support: 1705522849-26528 (-1532871985)
  Line number     = 6
  Column number   = 12

    ‌

    That was a clean log from opening the delete, switching to custom context and executing.

    ‌

    thanks

    ‌

  13. Scott Wells repo owner

    Justin, here's an IC2 build with additional debug logging for what happens between the entries in the logs you've provided. Please install it, reproduce the behavior, and attach the latest log.

  14. Justin Julicher reporter

    Hi Scott,

    ‌

    Here’s the extra info. Oh - I should have mentioned - these are files that exist only on the sandbox and don’t have a local version. Basically trying to clean up the sandbox….

    ‌

    2022-05-19 11:17:32,853 [  40290]   FINE - #c.i.i.b.ForceComBuilder - Creating a metadata package for module RealEstateSystemsSalesforce.
2022-05-19 11:17:32,853 [  40290]   FINE - #c.i.i.b.ForceComBuilder -   Creating a metadata package based on package.xml file manifest/package.xml.
2022-05-19 11:17:54,790 [  62227]   INFO - #c.i.i.b.DeleteFromServerAction - Deleting metadata from requested connection jjdev.
2022-05-19 11:17:54,790 [  62227]   FINE - #c.i.i.b.DeleteFromServerAction - Deleting from connection jjdev: { ApexClass[AmendmentsTableController], ApexClass[TrackedChange_Test], Flow[ContractDetailCongaBatchSendAnnualOutgoingEstimatesEmail], Flow[ContractDetailUpdateRentStartDate], Flow[OpportunityCreateTenantRecordAfterLeaseApproved], Flow[OpportunityTeamMemberAddUserPhone], Flow[PropertyCongaBatchAnnualOutgoingEstimates], Flow[Site_Potential_Vacancy_Calculator], Flow[SiteSetStatusToVacantScheduledDaily] }.
2022-05-19 11:17:54,790 [  62227]   FINE - #c.i.i.b.ForceComMetadataDeleter - Building documentsToDeploy from buildSelections.
2022-05-19 11:17:54,790 [  62227]   FINE - #c.i.i.b.ForceComMetadataDeleter -   Processing build selection ApexClass[AmendmentsTableController].
2022-05-19 11:17:54,790 [  62227]   FINE - #c.i.i.b.ForceComMetadataDeleter -     No file found for build selection.
2022-05-19 11:17:54,790 [  62227]   FINE - #c.i.i.b.ForceComMetadataDeleter -   Processing build selection ApexClass[TrackedChange_Test].
2022-05-19 11:17:54,790 [  62227]   FINE - #c.i.i.b.ForceComMetadataDeleter -     No file found for build selection.
2022-05-19 11:17:54,791 [  62228]   FINE - #c.i.i.b.ForceComMetadataDeleter -   Processing build selection Flow[ContractDetailCongaBatchSendAnnualOutgoingEstimatesEmail].
2022-05-19 11:17:54,791 [  62228]   FINE - #c.i.i.b.ForceComMetadataDeleter -     No file found for build selection.
2022-05-19 11:17:54,791 [  62228]   FINE - #c.i.i.b.ForceComMetadataDeleter -   Processing build selection Flow[ContractDetailUpdateRentStartDate].
2022-05-19 11:17:54,791 [  62228]   FINE - #c.i.i.b.ForceComMetadataDeleter -     No file found for build selection.
2022-05-19 11:17:54,791 [  62228]   FINE - #c.i.i.b.ForceComMetadataDeleter -   Processing build selection Flow[OpportunityCreateTenantRecordAfterLeaseApproved].
2022-05-19 11:17:54,791 [  62228]   FINE - #c.i.i.b.ForceComMetadataDeleter -     No file found for build selection.
2022-05-19 11:17:54,791 [  62228]   FINE - #c.i.i.b.ForceComMetadataDeleter -   Processing build selection Flow[OpportunityTeamMemberAddUserPhone].
2022-05-19 11:17:54,791 [  62228]   FINE - #c.i.i.b.ForceComMetadataDeleter -     No file found for build selection.
2022-05-19 11:17:54,791 [  62228]   FINE - #c.i.i.b.ForceComMetadataDeleter -   Processing build selection Flow[PropertyCongaBatchAnnualOutgoingEstimates].
2022-05-19 11:17:54,791 [  62228]   FINE - #c.i.i.b.ForceComMetadataDeleter -     No file found for build selection.
2022-05-19 11:17:54,791 [  62228]   FINE - #c.i.i.b.ForceComMetadataDeleter -   Processing build selection Flow[Site_Potential_Vacancy_Calculator].
2022-05-19 11:17:54,791 [  62228]   FINE - #c.i.i.b.ForceComMetadataDeleter -     No file found for build selection.
2022-05-19 11:17:54,791 [  62228]   FINE - #c.i.i.b.ForceComMetadataDeleter -   Processing build selection Flow[SiteSetStatusToVacantScheduledDaily].
2022-05-19 11:17:54,791 [  62228]   FINE - #c.i.i.b.ForceComMetadataDeleter -     No file found for build selection.
2022-05-19 11:17:54,794 [  62231]   FINE - #c.i.i.b.ForceComMetadataDeleter - Delete requested for the following:
Module: RealEstateSystemsSalesforce
Build selections: { ApexClass[AmendmentsTableController], ApexClass[TrackedChange_Test], Flow[ContractDetailCongaBatchSendAnnualOutgoingEstimatesEmail], Flow[ContractDetailUpdateRentStartDate], Flow[OpportunityCreateTenantRecordAfterLeaseApproved], Flow[OpportunityTeamMemberAddUserPhone], Flow[PropertyCongaBatchAnnualOutgoingEstimates], Flow[Site_Potential_Vacancy_Calculator], Flow[SiteSetStatusToVacantScheduledDaily] }
Documents to deploy: {  }
Deployment options: {
  "purgeOnDelete": false,
  "ignoreErrors": false,
  "ignoreWarnings": true,
  "checkOnly": false
}
For validate-on-edit: false
Check-only: false
Bulk deploy: true
2022-05-19 11:17:54,794 [  62231]   FINE - #c.i.i.b.ForceComMetadataDeleter - Building list of files to deploy.
2022-05-19 11:17:54,794 [  62231]   FINE - #c.i.i.b.ForceComMetadataDeleter -   deployFiles = {  }
2022-05-19 11:17:54,794 [  62231]   WARN - #c.i.i.b.ForceComMetadataDeleter - Nothing to deploy.
2022-05-19 11:17:54,880 [  62317]   INFO - STDOUT - Theme loaded
2022-05-19 11:17:54,996 [  62433]   INFO - #c.i.i.l.LicenseValidator - Server license validation result was Genuine.
2022-05-19 11:17:54,996 [  62433]   INFO - #c.i.i.l.LicenseValidator - Server license validation result was Genuine.
2022-05-19 11:17:55,011 [  62448]   INFO - #c.i.i.l.LicenseValidator - Illuminated Cloud features are enabled.
2022-05-19 11:17:55,011 [  62448]   INFO - #c.i.i.l.LicenseValidator - Server license validation result was Genuine.
2022-05-19 11:17:55,011 [  62448]   INFO - #c.i.i.l.LicenseValidator - Server license validation result was Genuine.
2022-05-19 11:17:55,011 [  62448]   INFO - #c.i.i.s.m.ModuleContentsSynchronizationListener - Initializing module contents synchronization listener.
2022-05-19 11:17:55,011 [  62448]   FINE - #c.i.i.b.IlluminatedCloudSaveAllAction - Checking to see if the overridden Save All action is active.
2022-05-19 11:17:55,011 [  62448]   FINE - #c.i.i.b.IlluminatedCloudSaveAllAction - The overridden Save All action is not active.
2022-05-19 11:17:55,011 [  62448]   INFO - #c.i.i.i.LanguageInjectionPostStartupActivity - Trying to initialize SOQL/RegExp string literal language injection.
2022-05-19 11:17:55,011 [  62448]   INFO - #c.i.i.r.IlluminatedCloudFileHandler - Initializing Illuminated Cloud file handler.
2022-05-19 11:17:55,011 [  62448]   INFO - #c.i.i.u.IlluminatedCloudMarkdownUtil - The Markdown plugin is enabled.
2022-05-19 11:17:55,429 [  62866]   INFO - STDOUT - Initialize stderr logger, severity=LOGSEVERITY_DISABLE
2022-05-19 11:17:55,528 [  62965]   INFO - #c.i.c.ComponentStoreImpl - Saving appEditorSettings took 16 ms, IlluminatedCloudSettings took 15 ms, VSTSSettings took 16 ms
2022-05-19 11:17:55,713 [  63150]   INFO - #c.i.c.ComponentStoreImpl - Saving Project(name=RealEstateSystemsSalesforce, containerState=COMPONENT_CREATED, componentStore=C:\Users\justinj\Workspace\RealEstateSystemsSalesforce)CommitMessageInspectionProfile took 15 ms, RunManager took 16 ms, VcsDirectoryMappings took 16 ms
2022-05-19 11:17:55,821 [  63258]   INFO - #c.i.i.v.IlluminatedCloudStartupVersionValidator - Successfully referenced EmbeddedMarkupUtil when project 'RealEstateSystemsSalesforce' was opened.
2022-05-19 11:17:55,821 [  63258]   INFO - #c.i.i.v.IlluminatedCloudStartupVersionValidator -   The IDE version is 2022.1.
2022-05-19 11:17:55,821 [  63258]   INFO - #c.i.i.v.IlluminatedCloudStartupVersionValidator - Verifying recommended minimum IDE version.
2022-05-19 11:17:55,866 [  63303]   INFO - #c.i.w.i.i.j.s.DelayedProjectSynchronizer - Workspace model loaded from cache. Syncing real project state into workspace model in 43587 ms. Thread[ApplicationImpl pooled thread 18,4,main]
2022-05-19 11:17:56,498 [  63935]   FINE - #c.i.u.CommandLineUtil - Running command line: 'C:/Program Files/npm/sfdx.cmd force:config:get defaultusername --json' from working directory: 'C:\Users\justinj\Workspace\RealEstateSystemsSalesforce'.
2022-05-19 11:17:56,504 [  63941]   FINE - #c.i.u.VariableLengthPollingInterval - C:/Program Files/npm/sfdx.cmd force:config:get defaultusername --json: Using polling interval 1000 ms for polling iteration 1.
2022-05-19 11:17:56,557 [  63994]   INFO - #c.i.i.f.IlluminatedCloudFileTypeRegistry - Registering external resources http://soap.sforce.com/2006/04/metadata and urn:metadata.tooling.soap.sforce.com as C:/Users/justinj/AppData/Roaming/JetBrains/WebStorm2022.1/plugins/IlluminatedCloud2/lib/IlluminatedCloud.jar!/xsd/metadata.xsd.
2022-05-19 11:17:57,516 [  64953]   FINE - #c.i.u.CommandLineUtil - Returning command-line response: CommandLineResponse{exitCode=0, output='{  "status": 0,  "result": [    {      "key": "defaultusername",      "location": "Local",      "value": "jjdev",      "path": "C:\\Users\\justinj\\Workspace\\RealEstateSystemsSalesforce\\.sfdx\\sfdx-config.json"    }  ]}', error='', duration=1018}
2022-05-19 11:18:09,909 [  77346]   INFO - #c.i.c.ComponentStoreImpl - Saving appIlluminatedCloudSettings took 11 ms
2022-05-19 11:18:09,942 [  77379]   INFO - #c.i.c.ComponentStoreImpl - Saving Project(name=RealEstateSystemsSalesforce, containerState=COMPONENT_CREATED, componentStore=C:\Users\justinj\Workspace\RealEstateSystemsSalesforce)RunManager took 16 ms

    ‌

  15. Scott Wells repo owner

    Gotcha. Easily reproduced with the knowledge that it's for org-only metadata. Interestingly it looks like this issue was introduced in May, 2021 as part of a refactoring. I'm really surprised that it's gone unnoticed (including by me) until now. I'll plan to incorporate a fix in next week's build as this week's is pretty much ready to go out the door. Thanks for providing all of the diagnostic info to get to an understanding of the core issue.

  20. Log in to comment
Assignee
–
Type
bug
Priority
minor
Status
resolved
Component
Metadata Deployment/Retrieval/Removal
Version
2.2.1.7
Votes
0
Watchers
1
Jira Software: the preferred issue tracker for Bitbucket. Join the team!