Cannot Authorize SFDC Dev Hub

[Josh LeQuire]

Steps to reproduce:

1. Create SFDX project in IJ IC
2. Navigate to manage connections in IC
3. Click globe icon to “Authorize Org with SFDC”
4. Enter alias, set organization type to “production,” login URL set to login.salesforce.com, click OK

Expected results:

Default browser (Chrome) would open, and I would authenticate into this Salesforce org to authorize this connection.

Actual result:

Receive alert: “Illuminated Cloud: No response found from org authorization.”

Screenshot:

[Scott Wells]

Hi, Josh. Can you enable debug logging for Salesforce DX as documented here:

http://www.illuminatedcloud.com/support/debuglogging

then reproduce the issue and share your idea.log (found using Help>Show Log in Explorer/Finder/Files)? That should let me troubleshoot what's happening here. Thanks!

[Josh LeQuire]

@Scott Wells thank you! I’ve updated the debug log, and I re-ran these steps. Here’s the output from the log file:

‌

2019-06-20 11:51:17,625 [ 143240] INFO - lij.diagnostic.DebugLogManager - Set DEBUG for the following categories: Metadata Deployment, #com.illuminatedcloud.intellij.builder.IlluminatedCloudSaveAllAction, #com.illuminatedcloud.intellij.builder.ForceComBuilder, #com.illuminatedcloud.intellij.builder.ForceComBuilderUtil, #com.illuminatedcloud.intellij.builder.ForceComMetadataApiDeployer, #com.illuminatedcloud.intellij.builder.ForceComSfdxMetadataDeployer, #com.illuminatedcloud.intellij.builder.ForceComSfdxDeployer, #com.illuminatedcloud.intellij.builder.ForceComToolingApiDeployer, #com.illuminatedcloud.intellij.builder.ForceComBuildFailureAnnotator, #com.illuminatedcloud.util.VariableLengthPollingInterval, Metadata Retrieval, #com.illuminatedcloud.intellij.builder.RefreshAction, #com.illuminatedcloud.intellij.builder.RetrieveAction, #com.illuminatedcloud.intellij.builder.ForceComBuilder, #com.illuminatedcloud.intellij.builder.ForceComBuilderUtil, #com.illuminatedcloud.intellij.builder.ForceComMetadataRetriever, #com.illuminatedcloud.intellij.builder.ForceComSfdxMetadataRetriever, #com.illuminatedcloud.intellij.builder.ForceComSfdxRetriever, #com.illuminatedcloud.intellij.util.IlluminatedCloudDiffUtil, #com.illuminatedcloud.util.VariableLengthPollingInterval, Metadata Removal, #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.util.VariableLengthPollingInterval, Salesforce DX, #com.illuminatedcloud.util.CommandLineUtil, #com.illuminatedcloud.intellij.sfdx.SfdxUtil, #com.illuminatedcloud.intellij.builder.ForceComSfdxDeployer, #com.illuminatedcloud.intellij.builder.ForceComSfdxRetriever, #com.illuminatedcloud.util.VariableLengthPollingInterval, Unit Testing, #com.illuminatedcloud.intellij.unittest.ApexUnitTestRunProcessHandler, #com.illuminatedcloud.util.VariableLengthPollingInterval, Code Coverage, #com.illuminatedcloud.intellij.coverage.ApexCoverageEngine, #com.illuminatedcloud.intellij.coverage.ApexCoverageSuite, Apex Debug Logs, #com.illuminatedcloud.client.ApexLogUtil, Apex Completion, #com.illuminatedcloud.intellij.completion.ApexCompletionContext, #com.illuminatedcloud.intellij.completion.ApexCompletionRule, #com.illuminatedcloud.intellij.completion.ApexVariantsCompletionRule, Visualforce/Lightning Completion, #com.illuminatedcloud.intellij.completion.EmbeddedMarkupCompletionContext, #com.illuminatedcloud.intellij.completion.EmbeddedMarkupCompletionRule, Static Resources, #com.illuminatedcloud.intellij.filetype.StaticResourceFileTypeDetector, #com.illuminatedcloud.intellij.resourcebundle.StaticResourceBundleUtil, Lightning Bundles, #com.illuminatedcloud.intellij.lightning.LightningBundleUtil, Module Validation, #com.illuminatedcloud.intellij.moduletype.IlluminatedCloudModuleValidator, Offline Symbol Table Generation, #com.illuminatedcloud.symtab.OfflineSymbolTable, #com.illuminatedcloud.symtab.OfflineSymbolTable!trace (extremely verbose), #com.illuminatedcloud.intellij.settings.project.OfflineSymbolTableGenerator, #com.illuminatedcloud.symtab.SystemDeclarations, Refactoring, #com.illuminatedcloud.intellij.refactoring.ApexCopyHandler, #com.illuminatedcloud.intellij.refactoring.IlluminatedCloudFileHandler, #com.illuminatedcloud.intellij.refactoring.IlluminatedCloudMoveFileHandler, #com.illuminatedcloud.intellij.refactoring.LightningBundleCopyHandler, Subscription Management, #com.illuminatedcloud.intellij.settings.module.ModuleContentsSynchronizationListener, PMD Apex/Lightning Lint, #com.illuminatedcloud.util.CommandLineUtil, #com.illuminatedcloud.util.VariableLengthPollingInterval
2019-06-20 11:51:17,875 [ 143490] INFO - mponents.impl.stores.StoreUtil - saveProjectsAndApp took 98 ms
2019-06-20 11:51:21,718 [ 147333] INFO - ellij.license.LicenseValidator - Server license validation result was Genuine.
2019-06-20 11:51:21,746 [ 147361] DEBUG - .IlluminatedCloudSaveAllAction - Checking to see if the overridden Save All action is active.
2019-06-20 11:51:21,746 [ 147361] DEBUG - .IlluminatedCloudSaveAllAction - The overridden Save All action is not active.
2019-06-20 11:51:26,981 [ 152596] DEBUG - .VariableLengthPollingInterval - SfdxForceAuthWebLogin: Using polling interval 1000 ms for polling iteration 1.
2019-06-20 11:51:36,650 [ 162265] INFO - ellij.license.LicenseValidator - Server license validation result was Genuine.
2019-06-20 11:51:36,656 [ 162271] DEBUG - .IlluminatedCloudSaveAllAction - Checking to see if the overridden Save All action is active.
2019-06-20 11:51:36,656 [ 162271] DEBUG - .IlluminatedCloudSaveAllAction - The overridden Save All action is not active.
2019-06-20 11:51:37,468 [ 163083] INFO - mponents.impl.stores.StoreUtil - saveProjectsAndApp took 83 ms
2019-06-20 11:51:43,681 [ 169296] INFO - mponents.impl.stores.StoreUtil - saveProjectsAndApp took 27 ms

[Scott Wells]

Thanks, Josh. That doesn't quite include the full log that I'll need to debug this. Here's what I see in my log when I perform an OAuth login:

2019-06-20 10:56:25,819 [70920577]  DEBUG - .VariableLengthPollingInterval - SfdxForceAuthWebLogin: Using polling interval 1000 ms for polling iteration 1. 
2019-06-20 10:56:25,824 [70920582]  DEBUG - atedcloud.util.CommandLineUtil - Running command line: 'C:/Program Files/Salesforce CLI/bin/sfdx.cmd force:auth:web:login -a demo -r https://login.salesforce.com/ --json' from working directory: 'C:\Users\Scott\dev\projects\IlluminatedCloudTestProjects\df17'. 
2019-06-20 10:56:25,844 [70920602]  DEBUG - .VariableLengthPollingInterval - C:/Program Files/Salesforce CLI/bin/sfdx.cmd force:auth:web:login -a demo -r https://login.salesforce.com/ --json: Using polling interval 1000 ms for polling iteration 1. 
...
2019-06-20 10:56:41,864 [70936622]  DEBUG - .VariableLengthPollingInterval - C:/Program Files/Salesforce CLI/bin/sfdx.cmd force:auth:web:login -a demo -r https://login.salesforce.com/ --json: Using polling interval 2000 ms for polling iteration 14. 
2019-06-20 10:56:41,982 [70936740]  DEBUG - .VariableLengthPollingInterval - SfdxForceAuthWebLogin: Using polling interval 2000 ms for polling iteration 14. 
2019-06-20 10:56:43,865 [70938623]  DEBUG - atedcloud.util.CommandLineUtil - Returning command-line response: CommandLineResponse{exitCode=0, output='{  "status": 0,  "result": {    "orgId": "XXX",    "username": "[e-mail]",    "accessToken": "XXX.YYY.ZZZ",    "instanceUrl": "https://myorg.my.salesforce.com",    "refreshToken": "XXX.YYY",    "loginUrl": "https://login.salesforce.com/",    "clientId": "PlatformCLI"  }}', error=''} 

Can you verify that Help>Debug Log Settings contains exactly the following (the leading #s are important):

#com.illuminatedcloud.util.CommandLineUtil
#com.illuminatedcloud.intellij.sfdx.SfdxUtil
#com.illuminatedcloud.intellij.builder.ForceComSfdxDeployer
#com.illuminatedcloud.intellij.builder.ForceComSfdxRetriever
#com.illuminatedcloud.util.VariableLengthPollingInterval

then repeat the operation and provide the excerpt of your log matching what I included above (likely with an error instead of a success message)?

[Josh LeQuire]

@Scott Wells please forgive my gaffe! I updated my debug settings to exactly what you pasted here, and here’s the latest output from my error log after I re-ran this issue:

‌

2019-06-20 13:18:11,777 [5357392] INFO - ellij.license.LicenseValidator - Server license validation result was Genuine.
2019-06-20 13:18:11,783 [5357398] DEBUG - .IlluminatedCloudSaveAllAction - Checking to see if the overridden Save All action is active.
2019-06-20 13:18:11,784 [5357399] DEBUG - .IlluminatedCloudSaveAllAction - The overridden Save All action is not active.
2019-06-20 13:18:13,655 [5359270] INFO - mponents.impl.stores.StoreUtil - saveProjectsAndApp took 5006330 ms
2019-06-20 13:18:16,713 [5362328] INFO - ellij.license.LicenseValidator - Server license validation result was Genuine.
2019-06-20 13:18:16,719 [5362334] DEBUG - .IlluminatedCloudSaveAllAction - Checking to see if the overridden Save All action is active.
2019-06-20 13:18:16,719 [5362334] DEBUG - .IlluminatedCloudSaveAllAction - The overridden Save All action is not active.
2019-06-20 13:18:19,910 [5365525] ERROR - figurationStore.JbXmlOutputter - Element [e-mail].@name=password probably contains sensitive information (file: ~/Library/Preferences/IdeaIC2019.1/options/illuminatedCloud.xml)
java.lang.Throwable: Element [e-mail].@name=password probably contains sensitive information (file: ~/Library/Preferences/IdeaIC2019.1/options/illuminatedCloud.xml)
at com.intellij.openapi.diagnostic.Logger.error(Logger.java:145)
at com.intellij.configurationStore.JbXmlOutputter.logSensitiveInformationError(JbXmlOutputter.kt:561)
at com.intellij.configurationStore.JbXmlOutputter.checkIsElementContainsSensitiveInformation(JbXmlOutputter.kt:523)
at com.intellij.configurationStore.JbXmlOutputter.writeContent(JbXmlOutputter.kt:262)
at com.intellij.configurationStore.JbXmlOutputter.printElement(JbXmlOutputter.kt:250)
at com.intellij.configurationStore.JbXmlOutputter.printContentRange(JbXmlOutputter.kt:341)
at com.intellij.configurationStore.JbXmlOutputter.writeContent(JbXmlOutputter.kt:280)
at com.intellij.configurationStore.JbXmlOutputter.printElement(JbXmlOutputter.kt:250)
at com.intellij.configurationStore.JbXmlOutputter.printContentRange(JbXmlOutputter.kt:341)
at com.intellij.configurationStore.JbXmlOutputter.writeContent(JbXmlOutputter.kt:280)
at com.intellij.configurationStore.JbXmlOutputter.printElement(JbXmlOutputter.kt:250)
at com.intellij.configurationStore.JbXmlOutputter.printContentRange(JbXmlOutputter.kt:341)
at com.intellij.configurationStore.JbXmlOutputter.writeContent(JbXmlOutputter.kt:280)
at com.intellij.configurationStore.JbXmlOutputter.printElement(JbXmlOutputter.kt:250)
at com.intellij.configurationStore.JbXmlOutputter.printContentRange(JbXmlOutputter.kt:341)
at com.intellij.configurationStore.JbXmlOutputter.writeContent(JbXmlOutputter.kt:280)
at com.intellij.configurationStore.JbXmlOutputter.printElement(JbXmlOutputter.kt:250)
at com.intellij.configurationStore.XmlDataWriter.write$intellij_platform_configurationStore_impl(XmlElementStorage.kt:274)
at com.intellij.configurationStore.StringDataWriter.write(XmlElementStorage.kt:421)
at com.intellij.configurationStore.DataWriter$DefaultImpls.write$default(XmlElementStorage.kt:402)
at com.intellij.configurationStore.XmlElementStorageKt.writeTo(XmlElementStorage.kt:413)
at com.intellij.configurationStore.FileBasedStorage$FileSaveSession.saveLocally(FileBasedStorage.kt:119)
at com.intellij.configurationStore.XmlElementStorage$XmlElementStorageSaveSession$SaveExecutor.save(XmlElementStorage.kt:166)
at com.intellij.configurationStore.SaveExecutorKt.executeSave(SaveExecutor.kt:91)
at com.intellij.configurationStore.SaveExecutorKt.saveSessions(SaveExecutor.kt:85)
at com.intellij.configurationStore.SaveSessionProducerManager$save$task$1.invoke(SaveExecutor.kt:68)
at com.intellij.configurationStore.SaveSessionProducerManager$save$task$1.invoke(SaveExecutor.kt:22)
at com.intellij.configurationStore.SaveSessionProducerManager.save$suspendImpl(SaveExecutor.kt:78)
at com.intellij.configurationStore.SaveSessionProducerManager.save(SaveExecutor.kt)
at com.intellij.configurationStore.ApplicationStoreImpl$doSave$2$1.invokeSuspend(ApplicationStoreImpl.kt:40)
at kotlin.coroutines.jvm.internal.BaseContinuationImpl.resumeWith(ContinuationImpl.kt:32)
at kotlinx.coroutines.DispatchedTask.run(Dispatched.kt:236)
at kotlinx.coroutines.EventLoopBase.processNextEvent(EventLoop.kt:123)
at kotlinx.coroutines.BlockingCoroutine.joinBlocking(Builders.kt:69)
at kotlinx.coroutines.BuildersKt__BuildersKt.runBlocking(Builders.kt:45)
at kotlinx.coroutines.BuildersKt.runBlocking(Unknown Source)
at kotlinx.coroutines.BuildersKt__BuildersKt.runBlocking$default(Builders.kt:35)
at kotlinx.coroutines.BuildersKt.runBlocking$default(Unknown Source)
at com.intellij.ide.SaveAndSyncHandlerImpl$saveAlarm$1.invoke(SaveAndSyncHandlerImpl.kt:43)
at com.intellij.ide.SaveAndSyncHandlerImpl$saveAlarm$1.invoke(SaveAndSyncHandlerImpl.kt:33)
at com.intellij.util.SingleAlarmKt$sam$java_lang_Runnable$0.run(SingleAlarm.kt)
at com.intellij.util.concurrency.QueueProcessor.runSafely(QueueProcessor.java:232)
at com.intellij.util.Alarm$Request.runSafely(Alarm.java:368)
at com.intellij.util.Alarm$Request.run(Alarm.java:355)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at com.intellij.util.concurrency.SchedulingWrapper$MyScheduledFutureTask.run(SchedulingWrapper.java:224)
at com.intellij.util.concurrency.BoundedTaskExecutor.doRun(BoundedTaskExecutor.java:220)
at com.intellij.util.concurrency.BoundedTaskExecutor.access$100(BoundedTaskExecutor.java:26)
at com.intellij.util.concurrency.BoundedTaskExecutor$2.lambda$run$0(BoundedTaskExecutor.java:198)
at com.intellij.util.ConcurrencyUtil.runUnderThreadName(ConcurrencyUtil.java:224)
at com.intellij.util.concurrency.BoundedTaskExecutor$2.run(BoundedTaskExecutor.java:194)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
2019-06-20 13:18:19,913 [5365528] ERROR - figurationStore.JbXmlOutputter - IntelliJ IDEA 2019.1.3 Build #IC-191.7479.19
2019-06-20 13:18:19,913 [5365528] ERROR - figurationStore.JbXmlOutputter - JDK: 1.8.0_202-release; VM: OpenJDK 64-Bit Server VM; Vendor: JetBrains s.r.o
2019-06-20 13:18:19,913 [5365528] ERROR - figurationStore.JbXmlOutputter - OS: Mac OS X
2019-06-20 13:18:19,932 [5365547] INFO - mponents.impl.stores.StoreUtil - saveProjectsAndApp took 36 ms
2019-06-20 13:18:21,430 [5367045] INFO - .script.IdeScriptEngineManager - javax.script.ScriptEngineManager initialized in 13 ms
2019-06-20 13:18:29,342 [5374957] INFO - lij.diagnostic.DebugLogManager - Set DEBUG for the following categories: #com.illuminatedcloud.util.CommandLineUtil, #com.illuminatedcloud.intellij.sfdx.SfdxUtil, #com.illuminatedcloud.intellij.builder.ForceComSfdxDeployer, #com.illuminatedcloud.intellij.builder.ForceComSfdxRetriever, #com.illuminatedcloud.util.VariableLengthPollingInterval
2019-06-20 13:18:29,514 [5375129] INFO - mponents.impl.stores.StoreUtil - saveProjectsAndApp took 23 ms
2019-06-20 13:18:33,384 [5378999] INFO - ellij.license.LicenseValidator - Server license validation result was Genuine.
2019-06-20 13:18:40,293 [5385908] DEBUG - .VariableLengthPollingInterval - SfdxForceAuthWebLogin: Using polling interval 1000 ms for polling iteration 1.
2019-06-20 13:18:46,975 [5392590] INFO - ellij.license.LicenseValidator - Server license validation result was Genuine.
2019-06-20 13:18:49,124 [5394739] INFO - mponents.impl.stores.StoreUtil - saveProjectsAndApp took 33 ms
2019-06-20 13:20:58,883 [5524498] INFO - mponents.impl.stores.StoreUtil - saveProjectsAndApp took 19 ms

[Scott Wells]

Thanks, Josh. I see what's going on. You haven't registered the sfdx executable with Illuminated Cloud. Please take a look at this documentation to get the correct base configuration:

https://bitbucket.org/RoseSilverSoftware/illuminatedcloud/wiki/User_Guide/Configuring_Application_Settings#markdown-header-salesforce-dx

I'll also disable the connection management actions that depend on that config to avoid others running into this confusion in the future.

[Josh LeQuire]

ooooh silly me now this makes total sense!!!! thank you @Scott Wells ! I have this working beautifully now

[Scott Wells]

No, silly me for leaving those buttons enabled when it doesn't make sense to click them! I've already implemented a fix for the next build. Thanks for bringing this to my attention!

[Josh LeQuire]

No worries, and thank you! I just assumed IC knew I had the CLI file installed (and where to find it), and I did not realize this was a prerequisite, but your fix will make that easier for folks going forward for sure. Thank you sir!

[Scott Wells]

As of 1.8.5.1/2.0.6.5 these actions are disabled if the Salesforce CLI isn't registered with IC.