Error when retrieving sandbox metadata

Issue #714 resolved
Luke Kozakewycz created an issue

When retrieving unpackaged metadata from a sandbox, I get the following error message pop up (for every single component in the org - that's a lot!):

nofilespecified.PNG

It specifies the .xml metadata file as well as the main file.

I deleted all the main folder + connection and even set up a new sandbox. That didn't work. I tried to remove all mention of SFDX as I wasn't using that - but still no luck.

2017-09-25 13:48:32,414 [6594690]  ERROR - api.util.objectTree.ObjectNode - IntelliJ IDEA 2017.2.4  Build #IC-172.4155.36 
2017-09-25 13:48:32,414 [6594690]  ERROR - api.util.objectTree.ObjectNode - JDK: 1.8.0_152-release 
2017-09-25 13:48:32,414 [6594690]  ERROR - api.util.objectTree.ObjectNode - VM: OpenJDK 64-Bit Server VM 
2017-09-25 13:48:32,414 [6594690]  ERROR - api.util.objectTree.ObjectNode - Vendor: JetBrains s.r.o 
2017-09-25 13:48:32,414 [6594690]  ERROR - api.util.objectTree.ObjectNode - OS: Windows 10 
2017-09-25 13:48:32,651 [6594927]   INFO - ellij.project.impl.ProjectImpl - 151 project components initialized in 190 ms 
2017-09-25 13:48:32,688 [6594964]   INFO - le.impl.ModuleManagerComponent - 1 module(s) loaded in 36 ms 
2017-09-25 13:48:32,818 [6595094]   INFO - CompilerBackwardReferenceIndex - backward reference index version differ due to: class java.io.FileNotFoundException 
2017-09-25 13:48:32,839 [6595115]   INFO - .diagnostic.PerformanceWatcher - Post-startup activities under progress took 4ms; general responsiveness: ok; EDT responsiveness: ok 
2017-09-25 13:48:32,846 [6595122]   INFO - tartup.impl.StartupManagerImpl - C:/Users/Luke/IdeaProjects/isv-dev/.idea case-sensitivity: expected=false actual=false 
2017-09-25 13:48:32,851 [6595127]   INFO - pl.projectlevelman.NewMappings - VCS Root: [] - [<Project>] 
2017-09-25 13:48:32,884 [6595160]   INFO - .diagnostic.PerformanceWatcher - Pushing properties took 14ms; general responsiveness: ok; EDT responsiveness: ok 
2017-09-25 13:48:32,889 [6595165]   INFO -       ScriptTemplatesProviders - [kts] loading script definitions [] using cp:  
2017-09-25 13:48:32,891 [6595167]   INFO -       ScriptTemplatesProviders - Templates provider KotlinCompilerScriptTemplatesSettings is invalid:  
2017-09-25 13:48:32,892 [6595168]   INFO -       ScriptTemplatesProviders - Templates provider Gradle Kotlin DSL is invalid: Unable to get Gradle home directory 
2017-09-25 13:48:32,895 [6595171]   INFO -       ScriptTemplatesProviders - Templates provider Gradle Script Kotlin is invalid: Unable to get Gradle home directory 
2017-09-25 13:48:32,895 [6595171]   INFO -       ScriptTemplatesProviders - [kts] loading script definitions [kotlin.script.templates.standard.ScriptTemplateWithArgs] using cp:  
2017-09-25 13:48:32,934 [6595210]   WARN - openapi.wm.impl.ToolWindowImpl - ToolWindow icons should be 13x13. Please fix ToolWindow (ID:  Anonymous Apex) or icon jar:file:/C:/Program%20Files%20(x86)/JetBrains/IntelliJ%20IDEA%20Community%20Edition%202017.1/lib/icons.jar!/nodes/ejbFinderMethod.png 
2017-09-25 13:48:32,937 [6595213]   WARN - openapi.wm.impl.ToolWindowImpl - ToolWindow icons should be 13x13. Please fix ToolWindow (ID:  SOQL Query) or icon jar:file:/C:/Program%20Files%20(x86)/JetBrains/IntelliJ%20IDEA%20Community%20Edition%202017.1/lib/icons.jar!/nodes/dataSchema.png 
2017-09-25 13:48:32,946 [6595222]   INFO - tor.impl.FileEditorManagerImpl - Project opening took 508 ms 
2017-09-25 13:48:34,914 [6597190]   INFO - .diagnostic.PerformanceWatcher - Indexable file iteration took 2030ms; general responsiveness: ok; EDT responsiveness: ok 
2017-09-25 13:48:34,914 [6597190]   INFO - indexing.UnindexedFilesUpdater - Unindexed files update started: 536 files to update 
2017-09-25 13:48:36,911 [6599187]   INFO - .diagnostic.PerformanceWatcher - Unindexed files update took 1997ms; general responsiveness: ok; EDT responsiveness: ok 
2017-09-25 13:48:36,937 [6599213]   INFO - ellij.license.LicenseValidator - Server license validation result was Genuine. 
2017-09-25 13:49:09,488 [6631764]   INFO - ellij.license.LicenseValidator - Server license validation result was Genuine. 
2017-09-25 13:49:21,675 [6643951]   WARN - lluminatedCloudModuleValidator - Connection AuraDev does not use an Illuminated cloud SDK. 
2017-09-25 13:49:26,231 [6648507]   INFO - cloud.client.ForceComApiClient - Login successful for connection 'isv-dev'. 
2017-09-25 13:49:26,232 [6648508]   INFO - y.ReflectionServiceFactoryBean - Creating Service {urn:partner.soap.sforce.com}PartnerService from class com.sforce.soap.partner.PartnerApi 
2017-09-25 13:49:52,946 [6675222]   INFO - ntellij.builder.RetrieveAction - Retrieving metadata from requested connection isv-dev into module isv-dev 
2017-09-25 13:49:53,823 [6676099]   WARN - .IlluminatedCloudExceptionUtil - C:\Users\Luke\AppData\Local\Temp\isv_dev_9419077b_8a17_474a_97cb_7b7f529c9dbc\tmp\unpackaged\pages\Pricing.page-meta.xml (The system cannot find the file specified) 
java.io.FileNotFoundException: C:\Users\Luke\AppData\Local\Temp\isv_dev_9419077b_8a17_474a_97cb_7b7f529c9dbc\tmp\unpackaged\pages\Pricing.page-meta.xml (The system cannot find the file specified)
    at java.io.FileInputStream.open0(Native Method)
    at java.io.FileInputStream.open(FileInputStream.java:195)
    at java.io.FileInputStream.<init>(FileInputStream.java:138)
    at com.intellij.openapi.util.io.FileUtil.performCopy(FileUtil.java:495)
    at com.intellij.openapi.util.io.FileUtil.copy(FileUtil.java:472)
    at com.illuminatedcloud.intellij.builder.ForceComMetadataRetriever.populate(SourceFile:1354)
    at com.illuminatedcloud.intellij.builder.ForceComMetadataRetriever.access$700(SourceFile:91)
    at com.illuminatedcloud.intellij.builder.ForceComMetadataRetriever$1.run(SourceFile:419)
    at com.intellij.openapi.progress.impl.CoreProgressManager$TaskRunnable.run(CoreProgressManager.java:718)
    at com.intellij.openapi.progress.impl.CoreProgressManager.lambda$runProcess$1(CoreProgressManager.java:170)
    at com.intellij.openapi.progress.impl.CoreProgressManager.registerIndicatorAndRun(CoreProgressManager.java:548)
    at com.intellij.openapi.progress.impl.CoreProgressManager.executeProcessUnderProgress(CoreProgressManager.java:493)
    at com.intellij.openapi.progress.impl.ProgressManagerImpl.executeProcessUnderProgress(ProgressManagerImpl.java:94)
    at com.intellij.openapi.progress.impl.CoreProgressManager.runProcess(CoreProgressManager.java:157)
    at com.intellij.openapi.progress.impl.ProgressManagerImpl$2.run(ProgressManagerImpl.java:165)
    at com.intellij.openapi.application.impl.ApplicationImpl$2.run(ApplicationImpl.java:342)
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
    at java.util.concurrent.FutureTask.run(FutureTask.java:266)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
    at java.lang.Thread.run(Thread.java:745)
2017-09-25 13:49:53,824 [6676100]   WARN - .IlluminatedCloudExceptionUtil - C:\Users\Luke\AppData\Local\Temp\isv_dev_9419077b_8a17_474a_97cb_7b7f529c9dbc\tmp\unpackaged\pages\Pricing.page (The system cannot find the file specified) 
java.io.FileNotFoundException: C:\Users\Luke\AppData\Local\Temp\isv_dev_9419077b_8a17_474a_97cb_7b7f529c9dbc\tmp\unpackaged\pages\Pricing.page (The system cannot find the file specified)
    at java.io.FileInputStream.open0(Native Method)
    at java.io.FileInputStream.open(FileInputStream.java:195)
    at java.io.FileInputStream.<init>(FileInputStream.java:138)
    at com.intellij.openapi.util.io.FileUtil.performCopy(FileUtil.java:495)
    at com.intellij.openapi.util.io.FileUtil.copy(FileUtil.java:472)
    at com.illuminatedcloud.intellij.builder.ForceComMetadataRetriever.populate(SourceFile:1354)
    at com.illuminatedcloud.intellij.builder.ForceComMetadataRetriever.access$700(SourceFile:91)
    at com.illuminatedcloud.intellij.builder.ForceComMetadataRetriever$1.run(SourceFile:419)
    at com.intellij.openapi.progress.impl.CoreProgressManager$TaskRunnable.run(CoreProgressManager.java:718)
    at com.intellij.openapi.progress.impl.CoreProgressManager.lambda$runProcess$1(CoreProgressManager.java:170)
    at com.intellij.openapi.progress.impl.CoreProgressManager.registerIndicatorAndRun(CoreProgressManager.java:548)
    at com.intellij.openapi.progress.impl.CoreProgressManager.executeProcessUnderProgress(CoreProgressManager.java:493)
    at com.intellij.openapi.progress.impl.ProgressManagerImpl.executeProcessUnderProgress(ProgressManagerImpl.java:94)
    at com.intellij.openapi.progress.impl.CoreProgressManager.runProcess(CoreProgressManager.java:157)
    at com.intellij.openapi.progress.impl.ProgressManagerImpl$2.run(ProgressManagerImpl.java:165)
    at com.intellij.openapi.application.impl.ApplicationImpl$2.run(ApplicationImpl.java:342)
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
    at java.util.concurrent.FutureTask.run(FutureTask.java:266)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
    at java.lang.Thread.run(Thread.java:745)
2017-09-25 13:54:07,405 [6929681]   INFO - ide.actions.ShowFilePathAction - 
Exit code 1 

Comments (19)

  1. Scott Wells repo owner

    Luke, assuming it's reproducible for a small subset of metadata being retrieved as well (just to keep you from having to dismiss so many dialogs!), can you reproduce it while the following is configured under Help>Debug Log Settings:

    #com.illuminatedcloud.intellij.builder.ForceComMetadataRetriever
    

    then send me that log? I'd like to see why it's unable to find these temporary files from the retrieved metadata.

  2. Luke Kozakewycz reporter

    Sure.

    2017-09-25 14:15:11,071 [8193347]   INFO - ntellij.builder.RetrieveAction - Retrieving metadata from requested connection isv-dev into module isv-dev 
    2017-09-25 14:15:11,071 [8193347]  DEBUG - lder.ForceComMetadataRetriever - Retrieving metadata for module isv-dev 
    2017-09-25 14:15:11,073 [8193349]  DEBUG - lder.ForceComMetadataRetriever - Creating a retrieve request for API version 40.0 
    2017-09-25 14:15:11,073 [8193349]  DEBUG - lder.ForceComMetadataRetriever - Retrieving selected metadata  (1 items). 
    2017-09-25 14:15:11,077 [8193353]  DEBUG - lder.ForceComMetadataRetriever - Sending retrieve request:
    {
      "apiVersion": 40.0,
      "singlePackage": false,
      "unpackaged": {
        "types": [
          {
            "members": [
              "Pricing"
            ],
            "name": "ApexPage"
          }
        ]
      }
    } 
    2017-09-25 14:15:11,977 [8194253]  DEBUG - lder.ForceComMetadataRetriever - Submitted retrieve request with async process ID 09S3D000000AXsbUAG 
    2017-09-25 14:15:11,977 [8194253]  DEBUG - lder.ForceComMetadataRetriever - Polling the status of the retrieve request. 
    2017-09-25 14:15:12,208 [8194484]  DEBUG - lder.ForceComMetadataRetriever - Status: Pending 
    2017-09-25 14:15:12,208 [8194484]  DEBUG - lder.ForceComMetadataRetriever - Retrieve request is still processing. Waiting 5000 milliseconds and trying again. 
    2017-09-25 14:15:17,208 [8199484]  DEBUG - lder.ForceComMetadataRetriever - Polling the status of the retrieve request. 
    2017-09-25 14:15:17,654 [8199930]  DEBUG - lder.ForceComMetadataRetriever - Status: Succeeded 
    2017-09-25 14:15:17,654 [8199930]  DEBUG - lder.ForceComMetadataRetriever - Retrieve complete. 
    2017-09-25 14:15:17,953 [8200229]  DEBUG - lder.ForceComMetadataRetriever - Retrieved an archive of size 2938 bytes. 
    2017-09-25 14:15:17,968 [8200244]  DEBUG - lder.ForceComMetadataRetriever - Wrote retrieved archive to C:\Users\Luke\AppData\Local\Temp\isv_dev_85450fe1_dd8b_4d72_b438_ae2414059e81.zip 
    2017-09-25 14:15:17,998 [8200274]  DEBUG - lder.ForceComMetadataRetriever - Extracted retrieved archive to C:\Users\Luke\AppData\Local\Temp\isv_dev_85450fe1_dd8b_4d72_b438_ae2414059e81\tmp 
    2017-09-25 14:15:18,002 [8200278]  DEBUG - lder.ForceComMetadataRetriever - Using the 'unpackaged' directory as the extract root directory. 
    2017-09-25 14:15:18,017 [8200293]  DEBUG - lder.ForceComMetadataRetriever - Extracting static resource bundles. 
    2017-09-25 14:15:18,019 [8200295]  DEBUG - lder.ForceComMetadataRetriever - No static resources directories were found in the retrieved metadata. 
    2017-09-25 14:15:18,019 [8200295]  DEBUG - lder.ForceComMetadataRetriever - Removing archive file. 
    2017-09-25 14:15:18,021 [8200297]  DEBUG - lder.ForceComMetadataRetriever - Refreshing the virtual file system before applying retrieval substitution rules. 
    2017-09-25 14:15:18,023 [8200299]  DEBUG - lder.ForceComMetadataRetriever - Refreshing the virtual file system before comparison. 
    2017-09-25 14:15:18,023 [8200299]  DEBUG - lder.ForceComMetadataRetriever - Populating files for module = isv-dev, diffRoot = C:\Users\Luke\AppData\Local\Temp\isv_dev_85450fe1_dd8b_4d72_b438_ae2414059e81\tmp, postRetrieveAction = POPULATE, onFinish = null 
    2017-09-25 14:15:18,023 [8200299]  DEBUG - lder.ForceComMetadataRetriever - retrievedRoot = C:\Users\Luke\AppData\Local\Temp\isv_dev_85450fe1_dd8b_4d72_b438_ae2414059e81\tmp 
    2017-09-25 14:15:18,023 [8200299]  DEBUG - lder.ForceComMetadataRetriever - localRoot = C:/Users/Luke/IdeaProjects/isv-dev 
    2017-09-25 14:15:18,025 [8200301]   WARN - .IlluminatedCloudExceptionUtil - C:\Users\Luke\AppData\Local\Temp\isv_dev_85450fe1_dd8b_4d72_b438_ae2414059e81\tmp\unpackaged\pages\Pricing.page-meta.xml (The system cannot find the file specified) 
    java.io.FileNotFoundException: C:\Users\Luke\AppData\Local\Temp\isv_dev_85450fe1_dd8b_4d72_b438_ae2414059e81\tmp\unpackaged\pages\Pricing.page-meta.xml (The system cannot find the file specified)
        at java.io.FileInputStream.open0(Native Method)
        at java.io.FileInputStream.open(FileInputStream.java:195)
        at java.io.FileInputStream.<init>(FileInputStream.java:138)
        at com.intellij.openapi.util.io.FileUtil.performCopy(FileUtil.java:495)
        at com.intellij.openapi.util.io.FileUtil.copy(FileUtil.java:472)
        at com.illuminatedcloud.intellij.builder.ForceComMetadataRetriever.populate(SourceFile:1354)
        at com.illuminatedcloud.intellij.builder.ForceComMetadataRetriever.access$700(SourceFile:91)
        at com.illuminatedcloud.intellij.builder.ForceComMetadataRetriever$1.run(SourceFile:419)
        at com.intellij.openapi.progress.impl.CoreProgressManager$TaskRunnable.run(CoreProgressManager.java:718)
        at com.intellij.openapi.progress.impl.CoreProgressManager.lambda$runProcess$1(CoreProgressManager.java:170)
        at com.intellij.openapi.progress.impl.CoreProgressManager.registerIndicatorAndRun(CoreProgressManager.java:548)
        at com.intellij.openapi.progress.impl.CoreProgressManager.executeProcessUnderProgress(CoreProgressManager.java:493)
        at com.intellij.openapi.progress.impl.ProgressManagerImpl.executeProcessUnderProgress(ProgressManagerImpl.java:94)
        at com.intellij.openapi.progress.impl.CoreProgressManager.runProcess(CoreProgressManager.java:157)
        at com.intellij.openapi.progress.impl.ProgressManagerImpl$2.run(ProgressManagerImpl.java:165)
        at com.intellij.openapi.application.impl.ApplicationImpl$2.run(ApplicationImpl.java:342)
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
    2017-09-25 14:15:18,026 [8200302]   WARN - .IlluminatedCloudExceptionUtil - C:\Users\Luke\AppData\Local\Temp\isv_dev_85450fe1_dd8b_4d72_b438_ae2414059e81\tmp\unpackaged\pages\Pricing.page (The system cannot find the file specified) 
    java.io.FileNotFoundException: C:\Users\Luke\AppData\Local\Temp\isv_dev_85450fe1_dd8b_4d72_b438_ae2414059e81\tmp\unpackaged\pages\Pricing.page (The system cannot find the file specified)
        at java.io.FileInputStream.open0(Native Method)
        at java.io.FileInputStream.open(FileInputStream.java:195)
        at java.io.FileInputStream.<init>(FileInputStream.java:138)
        at com.intellij.openapi.util.io.FileUtil.performCopy(FileUtil.java:495)
        at com.intellij.openapi.util.io.FileUtil.copy(FileUtil.java:472)
        at com.illuminatedcloud.intellij.builder.ForceComMetadataRetriever.populate(SourceFile:1354)
        at com.illuminatedcloud.intellij.builder.ForceComMetadataRetriever.access$700(SourceFile:91)
        at com.illuminatedcloud.intellij.builder.ForceComMetadataRetriever$1.run(SourceFile:419)
        at com.intellij.openapi.progress.impl.CoreProgressManager$TaskRunnable.run(CoreProgressManager.java:718)
        at com.intellij.openapi.progress.impl.CoreProgressManager.lambda$runProcess$1(CoreProgressManager.java:170)
        at com.intellij.openapi.progress.impl.CoreProgressManager.registerIndicatorAndRun(CoreProgressManager.java:548)
        at com.intellij.openapi.progress.impl.CoreProgressManager.executeProcessUnderProgress(CoreProgressManager.java:493)
        at com.intellij.openapi.progress.impl.ProgressManagerImpl.executeProcessUnderProgress(ProgressManagerImpl.java:94)
        at com.intellij.openapi.progress.impl.CoreProgressManager.runProcess(CoreProgressManager.java:157)
        at com.intellij.openapi.progress.impl.ProgressManagerImpl$2.run(ProgressManagerImpl.java:165)
        at com.intellij.openapi.application.impl.ApplicationImpl$2.run(ApplicationImpl.java:342)
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
    2017-09-25 14:15:53,261 [8235537]   INFO - ide.actions.ShowFilePathAction - 
    Exit code 1 
    
  3. Scott Wells repo owner

    Thanks, Luke. In this case, what are the contents of the directory C:\Users\Luke\AppData\Local\Temp\isv_dev_85450fe1_dd8b_4d72_b438_ae2414059e81\tmp? Is there anything there? What happens if you do a retrieve for merge instead of a refresh? Same thing?

  4. Luke Kozakewycz reporter

    Hi Scott,

    I've tried all possible variations of options and have had no luck. If I retrieve the Pricing VF page, I just get tmp/unpackaged/pages/ and no files inside the directory.

    This working for all other environments. It's just my sandbox it isn't working for.

  5. Scott Wells repo owner

    Okay. So I actually had another user complain about something very similar last night. Under that same tmp dir, do you see another folder called diff/src containing the files that you retrieved? If so, it's the exact same issue and I'm going to investigate it today and hopefully provide a fix ASAP.

  6. Luke Kozakewycz reporter

    Yes - there is a diff/src directory containing all of the files. That's interesting. Must be an issue with merging those files into the main directory.

  7. Scott Wells repo owner

    Okay, that's good to hear. It means that I'm debugging one issue instead of two! It should also be pretty easy to discern how/why it's ending up in that state. Let me see what I can figure out and hopefully I'll have a solution for you at some point today. I'll likely post it here as a test build for you to use for verification before putting out a new release.

  8. Scott Wells repo owner

    Luke and Kamil, would you guys mind checking out the SFDX build found here:

    https://bitbucket.org/RoseSilverSoftware/illuminatedcloud/issues/554/salesforce-dx-support

    and let me know if it solves the problem or not? It has a quite different implementation of file sorting during retrieval, and it's going to be the implementation of record starting in a couple of days anyway. If that does resolve the problem, great; if not, I'd prefer to focus my attentions there since. Please let me know your findings.

  9. Scott Wells repo owner

    Thanks, Luke. Kamil confirmed the same last night. I'm putting together a new test build right now that contains WAY more debug logging as the directory structure for the retrieved metadata is populated. I'll post it here very shortly and would appreciate it if you'd give it a whirl. It won't resolve the problem as it's just additional debug logging, but it should shine a bright light on whatever is happening so that I can solve it. I'll post the new build here in just a few.

  10. Scott Wells repo owner

    Okay, here's an updated test build with a ton more logging. Please repeat the operation after installing this build and send me the resulting log. Hopefully with that we'll be just steps away from a resolution. Thanks!

  11. Scott Wells repo owner

    Okay, Kamil sent me a log with extensive debugging and it was extremely helpful. Here's a new build with exactly one variable reference changed that hopefully resolves the issue. If so, I'm honestly shocked that no one else has ever run into this! Please let me know if this does or doesn't resolve the issue for you guys, and if it doesn't, please send over the latest log for my perusal as well. Thanks!

  12. Scott Wells repo owner

    BAM! So glad to hear that, Kamil! Seriously, looking at the resolution to this, I cannot believe that it's just now coming up... Okay, so sit tight on that build for now and I'll ensure that the fix is included in the next official release as well. Thanks much to both of you for your assistance in debugging this and, most importantly, your patience as we did so!

  13. Log in to comment