Liveliness and responsiveness
One of the simpler methods for monitoring the performance of a PingFederate deployment involves determining whether the PingFederate Server is available and responsive. To help you identify the status of a server, PingFederate provides a heartbeat request endpoint.
Heartbeat endpoint
If the PingFederate server is running, the process of sending a request to the endpoint /pf/heartbeat.ping
returns an HTTP 200
status. If the request times out or requires an extended amount of time to return, the server might be overloaded or experiencing other difficulties.
If a request requires more than two or three seconds to return, multiple factors in your PingFederate deployment might be responsible. We recommend that you develop a baseline for the desired response time by testing the heartbeat endpoint of your deployment at various times. This endpoint can be useful when load balancing a cluster of PingFederate instances. Some load balancers can alter the number of requests that are sent to a particular server based on the response code received, or the responsiveness of requests that are made to the heartbeat endpoint.
The output of the heartbeat endpoint can be modified to provide performance-related information, such as CPU and memory usage, and response times. The response metrics can help you make better auto-scaling decisions. The map size metrics can help you recognize performance issues.
The following example shows a report containing a sample of the PingFederate server metrics available from the heartbeat endpoint. This response does not include examples of every field. For a complete list of fields, see the server metrics table below.
{"items":[{
"cpu.load": "14.86",
"total.jvm.memory": "536.871 MB",
"free.jvm.memory": "305.31 MB",
"used.jvm.memory": "231.561 MB",
"total.physical.system.memory": "68.719 GB",
"total.free.physical.system.memory": "30789.845 MB",
"total.used.physical.system.memory": "37.93 GB",
"number.of.cpus": "12",
"response.statistics.count": "540",
"response.statistics.window.seconds": "15",
"response.time.statistics.90.percentile": "159.350784",
"response.time.statistics.max": "6343.0",
"response.time.statistics.mean": "23.522222222222222",
"response.time.statistics.min": "0.0",
"response.concurrency.statistics.90.percentile": "2.0625",
"response.concurrency.statistics.max": "2.0",
"response.concurrency.statistics.mean": "0.7222222222222222",
"response.concurrency.statistics.min": "0.0",
"response.http.status.1xx": "0",
"response.http.status.2xx": "360",
"response.http.status.3xx": "180",
"response.http.status.4xx": "0",
"response.http.status.5xx": "0",
"transaction.count": "240",
"transaction.errors": "0",
"total.transactions": "660",
"total.failed.transactions": "0",
"ds.JDBC.PFIndexDS.request.count": "360",
"ds.JDBC.PFIndexDS.response.time.90.percentile": "0.0",
"ds.JDBC.PFIndexDS.response.time.max": "3.0",
"ds.JDBC.PFIndexDS.response.time.mean": "0.030555555555555555",
"ds.JDBC.PFIndexDS.response.time.min": "0.0",
"ds.LDAP.LDAP-8C4A5F60684C90B9ECE388D2B7194F7909C804CF.request.count": "485",
"ds.LDAP.LDAP-8C4A5F60684C90B9ECE388D2B7194F7909C804CF.response.time.90.percentile": "50.29888",
"ds.LDAP.LDAP-8C4A5F60684C90B9ECE388D2B7194F7909C804CF.response.time.max": "5964.0",
"ds.LDAP.LDAP-8C4A5F60684C90B9ECE388D2B7194F7909C804CF.response.time.mean": "21.25773195876289",
"ds.LDAP.LDAP-8C4A5F60684C90B9ECE388D2B7194F7909C804CF.response.time.min": "0.0",
"adapter.CIAMHtml.lookupAuthN.90.percentile": "100.630528",
"adapter.CIAMHtml.lookupAuthN.count": "121",
"adapter.CIAMHtml.lookupAuthN.max": "6123.0",
"adapter.CIAMHtml.lookupAuthN.mean": "43.768595041322314",
"adapter.CIAMHtml.lookupAuthN.min": "0.98304",
"connection.https://pfdev.ping-eng.com:9031.jwks.90.percentile": "0.0",
"connection.https://pfdev.ping-eng.com:9031.jwks.count": "0",
"connection.https://pfdev.ping-eng.com:9031.jwks.max": "46.0",
"connection.https://pfdev.ping-eng.com:9031.jwks.mean": "0.0",
"connection.https://pfdev.ping-eng.com:9031.jwks.min": "0.0",
"connection.https://pfdev.ping-eng.com:9031.token.90.percentile": "3.93216",
"connection.https://pfdev.ping-eng.com:9031.token.count": "60",
"connection.https://pfdev.ping-eng.com:9031.token.max": "1044.0",
"connection.https://pfdev.ping-eng.com:9031.token.mean": "3.9",
"connection.https://pfdev.ping-eng.com:9031.token.min": "2.883584",
"connection.https://pfdev.ping-eng.com:9031.userinfo.90.percentile": "2.981888",
"connection.https://pfdev.ping-eng.com:9031.userinfo.count": "60",
"connection.https://pfdev.ping-eng.com:9031.userinfo.max": "18.0",
"connection.https://pfdev.ping-eng.com:9031.userinfo.mean": "2.4166666666666665",
"connection.https://pfdev.ping-eng.com:9031.userinfo.min": "0.98304",
"engine.jetty.queued.thread.pool.max.available.threads": "199",
"engine.jetty.queued.thread.pool.queue.size": "0",
"engine.jetty.queued.thread.pool.utilization.rate": "0.01507537688442211",
"engine.jetty.queued.thread.pool.utilized.threads": "3",
"idp.session.registry.session.map.size": "165",
"sp.session.registry.session.map.size": "165",
"session.state.attribute.map.size": "166",
"transaction.state.map.size": "1",
"atm.default.token.map.size": "0",
"cluster.members": "[172.31.28.63:7600, 172.31.29.114:7600]",
"cluster.rpc.addKeys.90.percentile": "0.0",
"cluster.rpc.addKeys.count": "0",
"cluster.rpc.addKeys.max": "0.0",
"cluster.rpc.addKeys.mean": "0.0",
"cluster.rpc.addKeys.min": "0.0",
"cluster.rpc.getAttr.90.percentile": "0.98304",
"cluster.rpc.getAttr.count": "423",
"cluster.rpc.getAttr.max": "1.0",
"cluster.rpc.getAttr.mean": "0.1276595744680851",
"cluster.rpc.getAttr.min": "0.0",
"cluster.rpc.getAuthnSessionInfo.90.percentile": "0.98304",
"cluster.rpc.getAuthnSessionInfo.count": "121",
"cluster.rpc.getAuthnSessionInfo.max": "4.0",
"cluster.rpc.getAuthnSessionInfo.mean": "0.371900826446281",
"cluster.rpc.getAuthnSessionInfo.min": "0.0",
"cluster.rpc.registerBeans.90.percentile": "0.98304",
"cluster.rpc.registerBeans.count": "121",
"cluster.rpc.registerBeans.max": "9.0",
"cluster.rpc.registerBeans.mean": "0.45454545454545453",
"cluster.rpc.registerBeans.min": "0.0",
"cluster.rpc.registerSriToUniqueUserKey.90.percentile": "0.98304",
"cluster.rpc.registerSriToUniqueUserKey.count": "122",
"cluster.rpc.registerSriToUniqueUserKey.max": "1.0",
"cluster.rpc.registerSriToUniqueUserKey.mean": "0.1885245901639344",
"cluster.rpc.registerSriToUniqueUserKey.min": "0.0",
"cluster.rpc.removeAttr.90.percentile": "0.98304",
"cluster.rpc.removeAttr.count": "361",
"cluster.rpc.removeAttr.max": "1.0",
"cluster.rpc.removeAttr.mean": "0.16620498614958448",
"cluster.rpc.removeAttr.min": "0.0",
"cluster.rpc.retrieveAndRemoveState.90.percentile": "0.98304",
"cluster.rpc.retrieveAndRemoveState.count": "180",
"cluster.rpc.retrieveAndRemoveState.max": "3.0",
"cluster.rpc.retrieveAndRemoveState.mean": "0.47222222222222227",
"cluster.rpc.retrieveAndRemoveState.min": "0.0",
"cluster.rpc.saveState.90.percentile": "0.98304",
"cluster.rpc.saveState.count": "180",
"cluster.rpc.saveState.max": "10.0",
"cluster.rpc.saveState.mean": "0.55",
"cluster.rpc.saveState.min": "0.0",
"cluster.rpc.setAttr.90.percentile": "0.98304",
"cluster.rpc.setAttr.count": "301",
"cluster.rpc.setAttr.max": "20.0",
"cluster.rpc.setAttr.mean": "0.318936877076412",
"cluster.rpc.setAttr.min": "0.0",
"cluster.rpc.synchronizeKeys.90.percentile": "2.883584",
"cluster.rpc.synchronizeKeys.count": "0",
"cluster.rpc.synchronizeKeys.max": "3.0",
"cluster.rpc.synchronizeKeys.mean": "0.0",
"cluster.rpc.synchronizeKeys.min": "2.883584"
}]}
The following table describes all the PingFederate server metrics available from the heartbeat endpoint.
In the following table, for server metrics that end in |
Server metrics | Description |
---|---|
|
Load on the PingFederate server’s cores as a percentage of total capacity |
|
Total memory of the JVM |
|
Free memory of the JVM |
|
Used memory of the JVM |
|
Total system memory |
|
Free system memory |
|
Used system memory |
|
Number of cores on the PingFederate server |
|
Number of items considered in the heartbeat report for the time and concurrency statistics |
|
Time interval (in seconds) for the statistics report (this is an echo of the |
|
The 90th percentile response time in milliseconds during the statistics window (for example, if this value is 168, then 90% of the report samples had response times below 168 milliseconds) |
|
Longest time in milliseconds that the PingFederate server took to respond during the statistics window |
|
Mean time in milliseconds that the PingFederate server took to respond during the statistics window |
|
Shortest time in milliseconds that the PingFederate server took to respond during the statistics window |
|
The 90th percentile response concurrency during the statistics window (for example, if this value is 124, then 90% of the report samples had response concurrency values below 124) |
|
Maximum number of HTTP requests that the PingFederate server processed concurrently during the statistics window |
|
Mean number of HTTP requests that the PingFederate server processed concurrently during the statistics window |
|
Minimum number of HTTP requests that the PingFederate server processed concurrently during the statistics window |
|
Number of 1xx HTTP response codes during the statistics window |
|
Number of 2xx HTTP response codes during the statistics window |
|
Number of 3xx HTTP response codes during the statistics window |
|
Number of 4xx HTTP response codes during the statistics window |
|
Number of 5xx HTTP response codes during the statistics window |
|
Number of SSO, SLO, and STS transactions during the statistics window |
|
Number of failed SSO, SLO, and STS transactions during the statistics window |
|
Total number of SSO, SLO, and STS transactions since the server started |
|
Total number of failed SSO, SLO, and STS transactions since the server started |
|
Number of requests for the data store during the statistics window |
|
The data store’s 90th percentile response time in milliseconds during the statistics window |
|
The data store’s mean response time in milliseconds during the statistics window |
|
The data store’s minimum response time in milliseconds during the statistics window |
|
The data store’s maximum response time in milliseconds during the statistics window |
|
Number of data store errors during the statistics window |
|
Number of authentication requests for the adapter during the statistics window |
|
The adapter’s 90th percentile response time in milliseconds during the statistics window |
|
The adapter’s mean response time in milliseconds during the statistics window |
|
The adapter’s minimum response time in milliseconds during the statistics window |
|
The adapter’s maximum response time in milliseconds during the statistics window |
|
Number of failed adapter authentication requests during the statistics window |
|
Number of requests for the OIDC identity provider (IdP) connection JWKS endpoint during the statistics window |
|
The OIDC IdP connection JWKS endpoint’s 90th percentile response time in milliseconds during the statistics window |
|
The OIDC IdP connection JWKS endpoint’s mean response time in milliseconds during the statistics window |
|
The OIDC IdP connection JWKS endpoint’s minimum response time in milliseconds during the statistics window |
|
The OIDC IdP connection JWKS endpoint’s maximum response time in milliseconds during the statistics window |
|
Number of failed OIDC IdP connection JWKS endpoint requests during the statistics window |
|
Number of requests for the OIDC IdP connection token endpoint during the statistics window |
|
The OIDC IdP connection token endpoint’s 90th percentile response time in milliseconds during the statistics window |
|
The OIDC IdP connection token endpoint’s mean response time in milliseconds during the statistics window |
|
The OIDC IdP connection token endpoint’s minimum response time in milliseconds during the statistics window |
|
The OIDC IdP connection token endpoint’s maximum response time in milliseconds during the statistics window |
|
Number of failed OIDC IdP connection token endpoint requests during the statistics window |
|
Number of requests for the OIDC IdP connection user info endpoint during the statistics window |
|
The OIDC IdP connection user info endpoint’s 90th percentile response time in milliseconds during the statistics window |
|
The OIDC IdP connection user info endpoint’s mean response time in milliseconds during the statistics window |
|
The OIDC IdP connection user info endpoint’s minimum response time in milliseconds during the statistics window |
|
The OIDC IdP connection user info endpoint’s maximum response time in milliseconds during the statistics window |
|
Number of failed OIDC IdP connection user info endpoint requests during the statistics window |
|
Number of requests for the SAML IdP connection artifact endpoint during the statistics window |
|
The SAML IdP connection artifact endpoint’s 90th percentile response time in milliseconds during the statistics window |
|
The SAML IdP connection artifact endpoint’s mean response time in milliseconds during the statistics window |
|
The SAML IdP connection artifact endpoint’s minimum response time in milliseconds during the statistics window |
|
The SAML IdP connection artifact endpoint’s maximum response time in milliseconds during the statistics window |
|
Number of failed SAML IdP connection artifact endpoint requests during the statistics window |
|
Number of threads in the Jetty thread pool that are currently in use |
|
Maximum number of threads in the Jetty thread pool |
|
The threads in the pool that are currently in use, as a fraction of the maximum available threads |
|
Number of requests currently queued waiting to be handled by a thread in the pool |
|
Number of IdP sessions |
|
Number of unexpired entries purged from the IdP session registry during the statistics window |
|
Number of service provider (SP) sessions |
|
Number of unexpired entries purged from the SP session registry during the statistics window |
|
Number of items in the session state attribute map |
|
Number of unexpired entries purged from the session state attribute map during the statistics window |
|
Number of items in the SSO transaction state map |
|
Number of unexpired entries purged from the SSO transaction state map during the statistics window |
|
Number of tokens in the access token manager with the ID specified by <atm> |
|
Holds the cluster membership list |
|
The synchronous cluster Remote Procedure Call (RPC)’s 90th percentile response time in milliseconds during the statistics window |
|
The synchronous cluster RPC’s mean response time in milliseconds during the statistics window |
|
The synchronous cluster RPC’s minimum response time in milliseconds during the statistics window |
|
The synchronous cluster RPC’s maximum response time in milliseconds during the statistics window |
|
Number of cases where the RPC received no valid responses |
As indicated in the table, the values of some metrics are calculated over a configurable time window. The default statistics window is five minutes.
To customize the statistics window period, change the value of the StatisticsWindowSecs
parameter in the <pf_install>/pingfederate/server/default/data/config-store/com.pingidentity.monitoring.MonitoringService.xml file
. This file also lets you specify additional JMX MBean attributes that will be made available to the heartbeat page templates.
For more information, see Customizing the heartbeat message.
Response-time logging
By default, the audit logs record the processing time for each transaction. With audit logging enabled, you can identify the speed with which PingFederate processes the following transaction types:
-
Single sign-on (SSO)
-
OAuth
-
Security token services (STS)
Depending on your logging configuration, audit logging might not log any transactions. For more information, see Security audit logging.
The following provides examples of the default audit log.
2019-11-10 13:24:57,493| tid:cYunBsgybiw_fiRnJjkAhbIXvzc| AUTHN_SESSION_USED| | 127.0.0.1 | | ac_client| | localhost| IdP| success| PdFormAdpt| | 17
2019-11-10 13:24:58,720| tid:cYunBsgybiw_fiRnJjkAhbIXvzc| OAuth| 5c60f022-1e9d-3fbe-9749-4b9ca5591356| 127.0.0.1 | | ac_client| OAuth20| localhost| AS| success| PdFormAdpt| | 7
Processing times are shown at the end of the entry in milliseconds.