Couple of below mentioned Processes of Identity server are taking lot of time to complete which leads to request callout by salesforce.
-HashedSharedSecretValidator
-Validation of authorization code token request
above methods are taking 3 and 4 seconds respectively
this delay not only happens with SAlesforce but for other login as well, but as SalesForce does not entertain requests taking longer than 10 Seconds, Login Fails.
Relevant parts of the log file
2017-08-07 21:44:05.073 -07:00 [Information] Start token request
2017-08-07 21:44:05.089 -07:00 [Debug] Start client validation
2017-08-07 21:44:05.089 -07:00 [Debug] Start parsing Basic Authentication secret
2017-08-07 21:44:05.089 -07:00 [Debug] Start parsing for secret in post body
2017-08-07 21:44:05.089 -07:00 [Debug] Parser found secret: "PostBodySecretParser"
2017-08-07 21:44:05.089 -07:00 [Information] Secret id found: "test_salesforce"
2017-08-07 21:44:08.240 -07:00 [Debug] Secret validator success: "HashedSharedSecretValidator"
Note: You can check above that hashing is taking 3 seconds and logs below shows that auth token validation takes almost Four seconds.
There are so many moving parts here. You will have to debug more to find out where the delay is coming from (for example any custom extensions to IdSvr).
scottbrady91
Question / Issue
Relevant parts of the log file
2017-08-07 21:44:05.073 -07:00 [Information] Start token request 2017-08-07 21:44:05.089 -07:00 [Debug] Start client validation 2017-08-07 21:44:05.089 -07:00 [Debug] Start parsing Basic Authentication secret 2017-08-07 21:44:05.089 -07:00 [Debug] Start parsing for secret in post body 2017-08-07 21:44:05.089 -07:00 [Debug] Parser found secret: "PostBodySecretParser" 2017-08-07 21:44:05.089 -07:00 [Information] Secret id found: "test_salesforce" 2017-08-07 21:44:08.240 -07:00 [Debug] Secret validator success: "HashedSharedSecretValidator"
Note: You can check above that hashing is taking 3 seconds and logs below shows that auth token validation takes almost Four seconds.
2017-08-07 21:44:08.303 -07:00 [Information] Start token request validation 2017-08-07 21:44:08.349 -07:00 [Information] Start validation of authorization code token request 2017-08-07 21:44:11.891 -07:00 [Information] Validation of authorization code token request success
Can you please help me with this.