==================================================================================================== ========================================= Pod describe ========================================= ==================================================================================================== Name: am-7cb58cf89f-rhtt7 Namespace: xlou-sp Priority: 0 Node: gke-xlou-cdm-default-pool-33cc2627-kjw0/10.142.0.123 Start Time: Sun, 07 Aug 2022 16:18:32 +0000 Labels: app=am app.kubernetes.io/component=am app.kubernetes.io/instance=am app.kubernetes.io/name=am app.kubernetes.io/part-of=forgerock pod-template-hash=7cb58cf89f skaffold.dev/profile=small skaffold.dev/run-id=xlou-sp tier=middle Annotations: cluster-autoscaler.kubernetes.io/safe-to-evict: true Status: Running IP: 10.0.7.137 IPs: IP: 10.0.7.137 Controlled By: ReplicaSet/am-7cb58cf89f Init Containers: fbc-init: Container ID: containerd://17757e550a30025220508da8c28f53d9c2b01eb89541fc4ae8d616320f45be1f Image: gcr.io/engineeringpit/lodestar-images/am:xlou-sp@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4 Image ID: gcr.io/engineeringpit/lodestar-images/am@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4 Port: Host Port: Command: /bin/bash -c if [ -d /fbc/config ]; then echo "Existing openam configuration found. Skipping copy" else echo "Copying docker image configuration files to the shared volume" cd /home/forgerock/openam cp -r .homeVersion * /fbc fi State: Terminated Reason: Completed Exit Code: 0 Started: Sun, 07 Aug 2022 16:18:36 +0000 Finished: Sun, 07 Aug 2022 16:18:36 +0000 Ready: True Restart Count: 0 Environment: Mounts: /fbc from fbc (rw) /var/run/secrets/kubernetes.io/serviceaccount from kube-api-access-8c7ln (ro) truststore-init: Container ID: containerd://40c841c37009bf3c780c99533a7c50efdb86168c72e402acb8af578dcbe6484b Image: gcr.io/engineeringpit/lodestar-images/am:xlou-sp@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4 Image ID: gcr.io/engineeringpit/lodestar-images/am@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4 Port: Host Port: Command: /home/forgerock/import-pem-certs.sh State: Terminated Reason: Completed Exit Code: 0 Started: Sun, 07 Aug 2022 16:18:37 +0000 Finished: Sun, 07 Aug 2022 16:18:37 +0000 Ready: True Restart Count: 0 Environment: TRUSTSTORE_PATH: /truststore/amtruststore TRUSTSTORE_PASSWORD: changeit AM_PEM_TRUSTSTORE: /var/run/secrets/truststore/cacerts Mounts: /truststore from new-truststore (rw) /var/run/secrets/kubernetes.io/serviceaccount from kube-api-access-8c7ln (ro) /var/run/secrets/truststore from truststore (rw) Containers: openam: Container ID: containerd://a71b9dc373cf425844a04495c78a0225560751530f5ef7467d3618b178fdf5d0 Image: gcr.io/engineeringpit/lodestar-images/am:xlou-sp@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4 Image ID: gcr.io/engineeringpit/lodestar-images/am@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4 Port: 8080/TCP Host Port: 0/TCP Command: bash /home/forgerock/docker-entrypoint.sh State: Running Started: Sun, 07 Aug 2022 16:18:38 +0000 Ready: True Restart Count: 0 Limits: memory: 8Gi Requests: cpu: 2 memory: 6Gi Liveness: http-get http://:8080/am/json/health/live delay=30s timeout=5s period=30s #success=1 #failure=3 Readiness: http-get http://:8080/am/json/health/ready delay=20s timeout=5s period=10s #success=1 #failure=3 Startup: http-get http://:8080/am/json/health/live delay=0s timeout=1s period=10s #success=1 #failure=40 Environment Variables from: am-env-secrets Secret Optional: false platform-config ConfigMap Optional: false amster-env-secrets Secret Optional: false ds-env-secrets Secret Optional: false Environment: NAMESPACE: xlou-sp (v1:metadata.namespace) AM_STORES_SSL_ENABLED: true TRUSTSTORE_PATH: /home/forgerock/amtruststore TRUSTSTORE_PASSWORD: changeit AM_STORES_USER_TYPE: LDAPv3ForForgeRockIAM Mounts: /home/forgerock/amtruststore from new-truststore (ro,path="amtruststore") /home/forgerock/openam from fbc (rw) /var/run/secrets/am from am-secrets (rw) /var/run/secrets/amster from amster-key (rw) /var/run/secrets/kubernetes.io/serviceaccount from kube-api-access-8c7ln (ro) Conditions: Type Status Initialized True Ready True ContainersReady True PodScheduled True Volumes: fbc: Type: EmptyDir (a temporary directory that shares a pod's lifetime) Medium: SizeLimit: am-secrets: Type: Projected (a volume that contains injected data from multiple sources) SecretName: am-passwords SecretOptionalName: SecretName: am-keystore SecretOptionalName: amster-key: Type: Secret (a volume populated by a Secret) SecretName: amster Optional: false truststore: Type: Secret (a volume populated by a Secret) SecretName: truststore-pem Optional: false new-truststore: Type: EmptyDir (a temporary directory that shares a pod's lifetime) Medium: SizeLimit: kube-api-access-8c7ln: Type: Projected (a volume that contains injected data from multiple sources) TokenExpirationSeconds: 3607 ConfigMapName: kube-root-ca.crt ConfigMapOptional: DownwardAPI: true QoS Class: Burstable Node-Selectors: Tolerations: node.kubernetes.io/not-ready:NoExecute op=Exists for 300s node.kubernetes.io/unreachable:NoExecute op=Exists for 300s Events: Type Reason Age From Message ---- ------ ---- ---- ------- Normal Scheduled 3m12s default-scheduler Successfully assigned xlou-sp/am-7cb58cf89f-rhtt7 to gke-xlou-cdm-default-pool-33cc2627-kjw0 Warning FailedMount 3m10s (x2 over 3m11s) kubelet MountVolume.SetUp failed for volume "truststore" : failed to sync secret cache: timed out waiting for the condition Warning FailedMount 3m10s kubelet MountVolume.SetUp failed for volume "am-secrets" : failed to sync secret cache: timed out waiting for the condition Normal Started 3m8s kubelet Started container fbc-init Normal Pulling 3m8s kubelet Pulling image "gcr.io/engineeringpit/lodestar-images/am:xlou-sp@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4" Normal Pulled 3m8s kubelet Successfully pulled image "gcr.io/engineeringpit/lodestar-images/am:xlou-sp@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4" in 213.224423ms Normal Created 3m8s kubelet Created container fbc-init Normal Started 3m7s kubelet Started container truststore-init Normal Pulling 3m7s kubelet Pulling image "gcr.io/engineeringpit/lodestar-images/am:xlou-sp@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4" Normal Created 3m7s kubelet Created container truststore-init Normal Pulled 3m7s kubelet Successfully pulled image "gcr.io/engineeringpit/lodestar-images/am:xlou-sp@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4" in 224.853918ms Normal Pulling 3m6s kubelet Pulling image "gcr.io/engineeringpit/lodestar-images/am:xlou-sp@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4" Normal Pulled 3m6s kubelet Successfully pulled image "gcr.io/engineeringpit/lodestar-images/am:xlou-sp@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4" in 401.033032ms Normal Created 3m6s kubelet Created container openam Normal Started 3m6s kubelet Started container openam Warning Unhealthy 2m22s (x5 over 3m2s) kubelet Startup probe failed: Get "http://10.0.7.137:8080/am/json/health/live": dial tcp 10.0.7.137:8080: connect: connection refused Warning Unhealthy 111s (x3 over 2m11s) kubelet Startup probe failed: Get "http://10.0.7.137:8080/am/json/health/live": context deadline exceeded (Client.Timeout exceeded while awaiting headers) ==================================================================================================== =========================================== Pod logs =========================================== ==================================================================================================== Copying docker image configuration files to the shared volume Copying /usr/local/openjdk-11/lib/security/cacerts to /truststore/amtruststore Found (1) certificates in /var/run/secrets/truststore/cacerts Importing (1) certificates into /truststore/amtruststore Certificate was added to keystore Import complete! Copying bootstrap files for legacy AMKeyProvider Waiting for User Store to be available Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint User Store is responding Waiting for CTS to be available Trying ds-cts-0.ds-cts:8080/alive endpoint CTS is responding Waiting for Application Store to be available Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Application Store is responding Waiting for Policy Store to be available Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Policy Store is responding Waiting for UMA Store to be available Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint UMA Store is responding Starting tomcat with opts: -server -Dcom.sun.services.debug.mergeall=on -Dcom.sun.identity.configuration.directory=/home/forgerock/openam -Dcom.iplanet.services.stats.state=off -Dcom.sun.identity.sm.sms_object_filebased_enabled=true -Dorg.forgerock.donotupgrade=true -DtomcatAccessLogDir=/proc/self/fd -DtomcatAccessLogFile=1 -XX:MaxRAMPercentage=75 -XX:InitialRAMPercentage=75 -XX:MaxTenuringThreshold=1 -Djava.security.egd=file:/dev/urandom -Dorg.forgerock.am.oauth2.consent.xui_path=/OAuth2_XUI -Dcom.forgerock.am.fileconfig.basepaths=/home/forgerock/base/config/services,/home/forgerock/cdk/config/services -Djavax.net.ssl.trustStore=/home/forgerock/amtruststore -Djavax.net.ssl.trustStorePassword=changeit -Djavax.net.ssl.trustStoreType=jks NOTE: Picked up JDK_JAVA_OPTIONS: --add-opens=java.base/java.lang=ALL-UNNAMED --add-opens=java.base/java.io=ALL-UNNAMED --add-opens=java.base/java.util=ALL-UNNAMED --add-opens=java.base/java.util.concurrent=ALL-UNNAMED --add-opens=java.rmi/sun.rmi.transport=ALL-UNNAMED 07-Aug-2022 16:19:31.384 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version name: Apache Tomcat/9.0.65 07-Aug-2022 16:19:31.386 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server built: Jul 14 2022 12:28:53 UTC 07-Aug-2022 16:19:31.387 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version number: 9.0.65.0 07-Aug-2022 16:19:31.387 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Name: Linux 07-Aug-2022 16:19:31.387 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Version: 5.4.188+ 07-Aug-2022 16:19:31.387 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Architecture: amd64 07-Aug-2022 16:19:31.387 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Java Home: /usr/local/openjdk-11 07-Aug-2022 16:19:31.387 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Version: 11.0.16+8 07-Aug-2022 16:19:31.387 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Vendor: Oracle Corporation 07-Aug-2022 16:19:31.387 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_BASE: /usr/local/tomcat 07-Aug-2022 16:19:31.387 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_HOME: /usr/local/tomcat 07-Aug-2022 16:19:31.395 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.lang=ALL-UNNAMED 07-Aug-2022 16:19:31.395 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.io=ALL-UNNAMED 07-Aug-2022 16:19:31.395 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.util=ALL-UNNAMED 07-Aug-2022 16:19:31.395 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.util.concurrent=ALL-UNNAMED 07-Aug-2022 16:19:31.396 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.rmi/sun.rmi.transport=ALL-UNNAMED 07-Aug-2022 16:19:31.396 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.config.file=/usr/local/tomcat/conf/logging.properties 07-Aug-2022 16:19:31.396 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager 07-Aug-2022 16:19:31.396 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djdk.tls.ephemeralDHKeySize=2048 07-Aug-2022 16:19:31.396 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.protocol.handler.pkgs=org.apache.catalina.webresources 07-Aug-2022 16:19:31.396 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dorg.apache.catalina.security.SecurityListener.UMASK=0027 07-Aug-2022 16:19:31.396 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.services.debug.mergeall=on 07-Aug-2022 16:19:31.396 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.identity.configuration.directory=/home/forgerock/openam 07-Aug-2022 16:19:31.396 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.iplanet.services.stats.state=off 07-Aug-2022 16:19:31.396 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.identity.sm.sms_object_filebased_enabled=true 07-Aug-2022 16:19:31.396 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dorg.forgerock.donotupgrade=true 07-Aug-2022 16:19:31.396 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -DtomcatAccessLogDir=/proc/self/fd 07-Aug-2022 16:19:31.396 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -DtomcatAccessLogFile=1 07-Aug-2022 16:19:31.397 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -XX:MaxRAMPercentage=75 07-Aug-2022 16:19:31.397 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -XX:InitialRAMPercentage=75 07-Aug-2022 16:19:31.397 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -XX:MaxTenuringThreshold=1 07-Aug-2022 16:19:31.397 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.security.egd=file:/dev/urandom 07-Aug-2022 16:19:31.397 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dorg.forgerock.am.oauth2.consent.xui_path=/OAuth2_XUI 07-Aug-2022 16:19:31.397 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.forgerock.am.fileconfig.basepaths=/home/forgerock/base/config/services,/home/forgerock/cdk/config/services 07-Aug-2022 16:19:31.397 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djavax.net.ssl.trustStore=/home/forgerock/amtruststore 07-Aug-2022 16:19:31.397 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djavax.net.ssl.trustStorePassword=changeit 07-Aug-2022 16:19:31.397 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djavax.net.ssl.trustStoreType=jks 07-Aug-2022 16:19:31.397 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dignore.endorsed.dirs= 07-Aug-2022 16:19:31.397 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.base=/usr/local/tomcat 07-Aug-2022 16:19:31.397 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.home=/usr/local/tomcat 07-Aug-2022 16:19:31.398 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.io.tmpdir=/usr/local/tomcat/temp 07-Aug-2022 16:19:31.401 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent Loaded Apache Tomcat Native library [1.2.35] using APR version [1.7.0]. 07-Aug-2022 16:19:31.401 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true], UDS [true]. 07-Aug-2022 16:19:31.401 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR/OpenSSL configuration: useAprConnector [false], useOpenSSL [true] 07-Aug-2022 16:19:31.403 INFO [main] org.apache.catalina.core.AprLifecycleListener.initializeSSL OpenSSL successfully initialized [OpenSSL 1.1.1n 15 Mar 2022] 07-Aug-2022 16:19:31.613 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8080"] 07-Aug-2022 16:19:31.629 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8081"] 07-Aug-2022 16:19:31.629 INFO [main] org.apache.catalina.startup.Catalina.load Server initialization in [391] milliseconds 07-Aug-2022 16:19:31.668 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Catalina] 07-Aug-2022 16:19:31.668 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.65] 07-Aug-2022 16:19:31.672 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/usr/local/tomcat/webapps/am] 07-Aug-2022 16:19:39.295 INFO [main] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time. Starting up OpenAM at Aug 7, 2022, 4:19:41 PM {"timestamp":"2022-08-07T16:19:44.696Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=10, blocked=0, ldapClient=LdapClient(host=ds-idrepo-2.ds-idrepo, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-idrepo-2.ds-idrepo' cannot be resolved","context":"default"} {"timestamp":"2022-08-07T16:19:44.940Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=10, blocked=0, ldapClient=LdapClient(host=ds-idrepo-2.ds-idrepo, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-idrepo-2.ds-idrepo' cannot be resolved","context":"default"} {"timestamp":"2022-08-07T16:19:45.006Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"org.forgerock.openam.plugins.PluginLifecycle","message":"PluginTools#upgradePlugins: Encountered plugin org.forgerock.openam.auth.nodes.CloudOnlyNodesPlugin not configured in SMS","context":"default"} {"timestamp":"2022-08-07T16:19:47.016Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.monitoring.MonitoringConfig","message":"JDMK runtime not found - Legacy Monitoring disabled","context":"default"} {"timestamp":"2022-08-07T16:19:47.028Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-07T16:19:47.080Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-07T16:19:48.930Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=10, blocked=0, ldapClient=LdapClient(host=UNUSED.IN.FBC, port=9999, protocol=LDAP (clear-text)))' is no longer operational: Connect Error: Host 'UNUSED.IN.FBC' cannot be resolved","context":"default"} {"timestamp":"2022-08-07T16:19:48.983Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.sm.ldap.LDAPEventManager","message":"LDAPEventManager: received all entries changed event from EventService","context":"default"} {"timestamp":"2022-08-07T16:19:49.025Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.sm.ldap.SMSLdapObject","message":"SMSLDAPObject: got notifications, all objects changed","context":"default"} {"timestamp":"2022-08-07T16:19:49.521Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.sm.ldap.LDAPEventManager","message":"LDAPEventManager: received all entries changed event from EventService","context":"default"} {"timestamp":"2022-08-07T16:19:49.539Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.sm.ldap.SMSLdapObject","message":"SMSLDAPObject: got notifications, all objects changed","context":"default"} {"timestamp":"2022-08-07T16:19:49.627Z","level":"WARN","thread":"LDAP SDK Default Scheduler","logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=10, blocked=0, ldapClient=LdapClient(host=ds-idrepo-1.ds-idrepo, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-idrepo-1.ds-idrepo' cannot be resolved","context":"default"} {"timestamp":"2022-08-07T16:19:49.941Z","level":"WARN","thread":"LDAP SDK Default Scheduler","logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=10, blocked=0, ldapClient=LdapClient(host=ds-idrepo-1.ds-idrepo, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-idrepo-1.ds-idrepo' cannot be resolved","context":"default"} {"timestamp":"2022-08-07T16:19:49.987Z","level":"WARN","thread":"RADIUS-RadiusServerManager","logger":"org.forgerock.openam.radius.server.config.RadiusServerManager","message":"RADIUS service is not enabled.","context":"default"} {"timestamp":"2022-08-07T16:19:50.032Z","level":"WARN","thread":"Thread-5","logger":"org.forgerock.openam.services.push.PushNotificationService","message":"No Push Notification Service Config found for realm /","context":"default","exception":"java.util.NoSuchElementException: No value present\n\tat java.base/java.util.Optional.get(Optional.java:148)\n\tat org.forgerock.openam.services.push.PushNotificationServiceConfigHelperFactory.getConfigHelperFor(PushNotificationServiceConfigHelperFactory.java:58)\n\tat org.forgerock.openam.services.push.PushNotificationService.getConfigHelper(PushNotificationService.java:280)\n\tat org.forgerock.openam.services.push.PushNotificationService.updatePreferences(PushNotificationService.java:242)\n\tat org.forgerock.openam.services.push.PushNotificationService.init(PushNotificationService.java:157)\n\tat org.forgerock.openam.services.push.PushNotificationServiceSetupListener$1.run(PushNotificationServiceSetupListener.java:48)\n\tat java.base/java.lang.Thread.run(Thread.java:829)\n"} {"timestamp":"2022-08-07T16:19:50.032Z","level":"WARN","thread":"Thread-5","logger":"org.forgerock.openam.services.push.PushNotificationService","message":"No Push Notification Delegate configured for realm /","context":"default"} {"timestamp":"2022-08-07T16:19:50.349Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.iplanet.services.naming.WebtopNaming","message":"WebtopNaming.configMonitoring: start generating ssoserverinfo\n Start time = 2022-08-07 16:19:50","context":"default"} {"timestamp":"2022-08-07T16:19:50.353Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.monitoring.MonitoringAdapters","message":"Agent.startAgent:Monitoring HTML port not enabled in configuration or JDMK not present.","context":"default"} {"timestamp":"2022-08-07T16:19:50.354Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.monitoring.MonitoringAdapters","message":"Agent.startAgent:Monitoring SNMP port not enabled or JDMK not present.","context":"default"} {"timestamp":"2022-08-07T16:19:50.354Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.monitoring.MonitoringAdapters","message":"Agent.startAgent:Monitoring RMI port not enabled.","context":"default"} {"timestamp":"2022-08-07T16:19:50.389Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.sm.ldap.SMSLdapObject","message":"SMSLdapObject:entryExists: LdapSmsEntryUid{dn='ou=default,ou=OrganizationConfig,ou=1.0,ou=sunFMCOTConfigService,ou=services,ou=am-config', dataStoreId=DataStoreId{id='application-store'}} does not exist","context":"default"} {"timestamp":"2022-08-07T16:19:50.397Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.sm.ldap.SMSLdapObject","message":"SMSLdapObject:entryExists: LdapSmsEntryUid{dn='ou=default,ou=OrganizationConfig,ou=1.0,ou=sunFMWSFederationMetadataService,ou=services,ou=am-config', dataStoreId=DataStoreId{id='application-store'}} does not exist","context":"default"} {"timestamp":"2022-08-07T16:19:50.403Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-07T16:19:50.407Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.log.s1is.LogConfigReader","message":"LogConfigReader: DB password is null","context":"default"} {"timestamp":"2022-08-07T16:19:50.407Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.log.s1is.LogConfigReader","message":"LogConfigReader: Logfile prefix string is null","context":"default"} {"timestamp":"2022-08-07T16:19:50.408Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.log.s1is.LogConfigReader","message":"LogConfigReader: Filter class is null","context":"default"} {"timestamp":"2022-08-07T16:19:50.412Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-07T16:19:50.428Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-07T16:19:50.431Z","level":"WARN","thread":"main","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-1"},"logger":"com.sun.identity.monitoring.FederationMonitoringSetupImpl","message":"JDMK runtime not found - Federation SNMP disabled","context":"default"} 07-Aug-2022 16:19:53.064 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/usr/local/tomcat/webapps/am] has finished in [21,390] ms 07-Aug-2022 16:19:53.068 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8080"] 07-Aug-2022 16:19:53.076 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8081"] 07-Aug-2022 16:19:53.078 INFO [main] org.apache.catalina.startup.Catalina.start Server startup in [21448] milliseconds {"timestamp":"2022-08-07T16:19:53.225Z","level":"WARN","thread":"http-nio-8080-exec-3","logger":"org.forgerock.openam.core.rest.docs.api.ApiDocsService","message":"API Descriptor has changed - regenerating docs","context":"default"} {"timestamp":"2022-08-07T16:19:55.819Z","level":"WARN","thread":"LDAP SDK Default Scheduler","logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=66, blocked=0, ldapClient=LdapClient(host=ds-cts-2.ds-cts, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-cts-2.ds-cts' cannot be resolved","context":"default"} {"timestamp":"2022-08-07T16:19:58.911Z","level":"WARN","thread":"LDAP SDK Default Scheduler","logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=2, blocked=0, ldapClient=LdapClient(host=ds-idrepo-2.ds-idrepo, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-idrepo-2.ds-idrepo' cannot be resolved","context":"default"} {"timestamp":"2022-08-07T16:19:58.911Z","level":"WARN","thread":"LDAP SDK Default AsyncRx I/O Thread (0)","logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=2, blocked=0, ldapClient=LdapClient(host=ds-idrepo-1.ds-idrepo, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Connection refused","context":"default"} 10.0.7.1 - - [07/Aug/2022:16:19:53 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 601ms 10.0.7.1 - - [07/Aug/2022:16:19:53 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 601ms 10.0.7.1 - - [07/Aug/2022:16:19:53 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 601ms 10.0.6.22 - - [07/Aug/2022:16:19:53 +0000] "GET /am/json/metrics/prometheus HTTP/1.1" 200 193265 562ms 10.0.7.1 - - [07/Aug/2022:16:20:02 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 4ms 10.0.7.1 - - [07/Aug/2022:16:20:12 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 3ms {"timestamp":"2022-08-07T16:20:20.473Z","level":"WARN","thread":"http-nio-8081-exec-3","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-162"},"logger":"org.forgerock.openam.core.realms.impl.DefaultRealmLookup","message":"DefaultRealms:lookup Unable to find Org name for: authenticate","context":"default"} {"timestamp":"2022-08-07T16:20:20.474Z","level":"DEBUG","thread":"http-nio-8081-exec-3","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-162"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-07T16:20:20.474Z","level":"DEBUG","thread":"http-nio-8081-exec-3","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-162"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-07T16:20:20.654Z","level":"WARN","thread":"http-nio-8081-exec-3","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-162"},"logger":"com.sun.identity.authentication.config.AMAuthLevelManager","message":"No auth level for module OpenIdConnect","context":"default"} {"timestamp":"2022-08-07T16:20:20.655Z","level":"WARN","thread":"http-nio-8081-exec-3","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-162"},"logger":"com.sun.identity.authentication.config.AMAuthLevelManager","message":"No auth level for module AuthenticatorPushRegistration","context":"default"} {"timestamp":"2022-08-07T16:20:20.655Z","level":"WARN","thread":"http-nio-8081-exec-3","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-162"},"logger":"com.sun.identity.authentication.config.AMAuthLevelManager","message":"No auth level for module PersistentCookie","context":"default"} {"timestamp":"2022-08-07T16:20:20.708Z","eventName":"AM-ACCESS-OUTCOME","transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-162","trackingIds":["f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-165"],"client":{"ip":"10.0.11.72","port":60812},"http":{"request":{"secure":true,"method":"POST","path":"https://am:80/am/json/authenticate","headers":{"accept-api-version":["protocol=2.1"],"content-type":["application/json; charset=UTF-8"],"host":["am:80"],"user-agent":["Apache-HttpAsyncClient/4.1.4 (Java/11.0.16)"],"x-requested-with":["Amster"]},"queryParameters":{"authIndexType":["service"],"authIndexValue":["amsterService"]}}},"response":{"status":"SUCCESSFUL","statusCode":"200","elapsedTime":286,"elapsedTimeUnits":"MILLISECONDS"},"realm":"/","component":"Authentication","source":"audit","topic":"access","level":"INFO","_eventId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-167"} {"timestamp":"2022-08-07T16:20:20.830Z","level":"DEBUG","thread":"http-nio-8081-exec-4","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-168"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-07T16:20:20.830Z","level":"DEBUG","thread":"http-nio-8081-exec-4","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-168"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-07T16:20:20.847Z","level":"WARN","thread":"http-nio-8081-exec-4","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-168"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-07T16:20:20.940Z","level":"WARN","thread":"http-nio-8081-exec-4","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-168"},"logger":"com.sun.identity.idm.plugins.internal.AgentsRepo","message":"AgentsRepo.getAgentGroupConfig: Unable to get Agent Group Config due to The instance agentgroup does not exist","context":"default"} {"timestamp":"2022-08-07T16:20:20.988Z","eventName":"AM-LOGIN-MODULE-COMPLETED","transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-168","trackingIds":["f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-165"],"userId":"id=amadmin,ou=user,ou=am-config","principal":["amadmin"],"entries":[{"moduleId":"Amster","info":{"authIndex":"service","authControlFlag":"REQUIRED","moduleClass":"Amster","ipAddress":"10.0.11.72","authLevel":"0"}}],"result":"SUCCESSFUL","realm":"/","component":"Authentication","source":"audit","topic":"authentication","level":"INFO","_eventId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-172"} {"timestamp":"2022-08-07T16:20:21.168Z","level":"WARN","thread":"http-nio-8081-exec-4","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-168"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-07T16:20:21.153Z","eventName":"AM-SESSION-CREATED","transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-168","trackingIds":["f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-165"],"userId":"id=amadmin,ou=user,ou=am-config","runAs":"id=amadmin,ou=user,ou=am-config","objectId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-165","operation":"CREATE","realm":"/","component":"Session","source":"audit","topic":"activity","level":"INFO","_eventId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-179"} {"timestamp":"2022-08-07T16:20:21.178Z","eventName":"AM-LOGIN-COMPLETED","transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-168","trackingIds":["f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-165"],"userId":"id=amadmin,ou=user,ou=am-config","principal":["amadmin"],"entries":[{"moduleId":"Amster","info":{"authIndex":"service","authIndexValue":"amsterService","ipAddress":"10.0.11.72","authLevel":"0"}}],"result":"SUCCESSFUL","realm":"/","component":"Authentication","source":"audit","topic":"authentication","level":"INFO","_eventId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-183"} {"timestamp":"2022-08-07T16:20:21.195Z","eventName":"AM-ACCESS-OUTCOME","transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-168","trackingIds":["f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-165"],"userId":"id=amadmin,ou=user,ou=am-config","client":{"ip":"10.0.11.72","port":60812},"http":{"request":{"secure":true,"method":"POST","path":"https://am:80/am/json/authenticate","headers":{"accept-api-version":["protocol=2.1"],"content-type":["application/json; charset=UTF-8"],"host":["am:80"],"user-agent":["Apache-HttpAsyncClient/4.1.4 (Java/11.0.16)"],"x-requested-with":["Amster"]},"queryParameters":{"authIndexType":["service"],"authIndexValue":["amsterService"]}}},"response":{"status":"SUCCESSFUL","statusCode":"200","elapsedTime":366,"elapsedTimeUnits":"MILLISECONDS"},"realm":"/","component":"Authentication","source":"audit","topic":"access","level":"INFO","_eventId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-189"} {"timestamp":"2022-08-07T16:20:21.204Z","level":"WARN","thread":"http-nio-8081-exec-5","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-190"},"logger":"org.forgerock.openam.core.realms.impl.DefaultRealmLookup","message":"DefaultRealms:lookup Unable to find Org name for: serverinfo","context":"default"} {"timestamp":"2022-08-07T16:20:21.204Z","level":"DEBUG","thread":"http-nio-8081-exec-5","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-190"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-07T16:20:21.204Z","level":"DEBUG","thread":"http-nio-8081-exec-5","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-190"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-07T16:20:21.240Z","eventName":"AM-ACCESS-OUTCOME","transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-190","client":{"ip":"10.0.11.72","port":60812},"server":{"ip":"10.0.7.137","port":8081},"http":{"request":{"secure":true,"method":"GET","path":"https://am:80/am/json/serverinfo/*","headers":{"accept-api-version":["protocol=2.1"],"host":["am:80"],"user-agent":["Apache-HttpAsyncClient/4.1.4 (Java/11.0.16)"]}}},"request":{"protocol":"CREST","operation":"READ"},"response":{"status":"SUCCESSFUL","statusCode":"","elapsedTime":40,"elapsedTimeUnits":"MILLISECONDS","detail":{"revision":"-485253810"}},"realm":"/","component":"Server Info","source":"audit","topic":"access","level":"INFO","_eventId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-194"} 10.0.11.72 - - [07/Aug/2022:16:20:20 +0000] "POST /am/json/authenticate?authIndexType=service&authIndexValue=amsterService HTTP/1.1" 200 668 300ms 10.0.11.72 - - [07/Aug/2022:16:20:21 +0000] "POST /am/json/authenticate?authIndexType=service&authIndexValue=amsterService HTTP/1.1" 200 167 368ms 10.0.11.72 - - [07/Aug/2022:16:20:21 +0000] "GET /am/json/serverinfo/* HTTP/1.1" 200 544 50ms 10.0.7.1 - - [07/Aug/2022:16:20:22 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 2ms {"timestamp":"2022-08-07T16:20:30.876Z","level":"WARN","thread":"LDAP SDK Default Scheduler","logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=14, blocked=0, ldapClient=LdapClient(host=ds-idrepo-2.ds-idrepo, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-idrepo-2.ds-idrepo' cannot be resolved","context":"default"} {"timestamp":"2022-08-07T16:20:30.877Z","level":"WARN","thread":"LDAP SDK Default Scheduler","logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=14, blocked=0, ldapClient=LdapClient(host=ds-idrepo-2.ds-idrepo, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-idrepo-2.ds-idrepo' cannot be resolved","context":"default"} {"timestamp":"2022-08-07T16:20:30.892Z","level":"WARN","thread":"LDAP SDK Default Scheduler","logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=2, blocked=0, ldapClient=LdapClient(host=ds-idrepo-2.ds-idrepo, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-idrepo-2.ds-idrepo' cannot be resolved","context":"default"} 10.0.6.22 - - [07/Aug/2022:16:20:23 +0000] "GET /am/json/metrics/prometheus HTTP/1.1" 200 349853 83ms 10.0.7.1 - - [07/Aug/2022:16:20:32 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 3ms 10.0.7.1 - - [07/Aug/2022:16:20:32 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 3ms 10.0.7.1 - - [07/Aug/2022:16:20:42 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 3ms 10.0.7.1 - - [07/Aug/2022:16:20:52 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 2ms 10.0.6.22 - - [07/Aug/2022:16:20:53 +0000] "GET /am/json/metrics/prometheus HTTP/1.1" 200 349841 35ms 10.0.7.1 - - [07/Aug/2022:16:21:02 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 3ms 10.0.7.1 - - [07/Aug/2022:16:21:02 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 3ms 10.0.7.1 - - [07/Aug/2022:16:21:12 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 3ms {"timestamp":"2022-08-07T16:21:14.707Z","level":"DEBUG","thread":"http-nio-8081-exec-1","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-228"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-07T16:21:14.707Z","level":"DEBUG","thread":"http-nio-8081-exec-1","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-228"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-07T16:21:14.787Z","eventName":"AM-LOGIN-MODULE-COMPLETED","transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-228","trackingIds":["f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-231"],"userId":"id=amadmin,ou=user,ou=am-config","principal":["amadmin"],"entries":[{"moduleId":"DataStore","info":{"authControlFlag":"REQUIRED","moduleClass":"DataStore","ipAddress":"10.0.6.14","authLevel":"0"}}],"result":"SUCCESSFUL","realm":"/","component":"Authentication","source":"audit","topic":"authentication","level":"INFO","_eventId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-233"} {"timestamp":"2022-08-07T16:21:14.803Z","eventName":"AM-SESSION-CREATED","transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-228","trackingIds":["f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-231"],"userId":"id=amadmin,ou=user,ou=am-config","runAs":"id=amadmin,ou=user,ou=am-config","objectId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-231","operation":"CREATE","realm":"/","component":"Session","source":"audit","topic":"activity","level":"INFO","_eventId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-237"} {"timestamp":"2022-08-07T16:21:14.804Z","eventName":"AM-LOGIN-COMPLETED","transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-228","trackingIds":["f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-231"],"userId":"id=amadmin,ou=user,ou=am-config","principal":["amadmin"],"entries":[{"moduleId":"DataStore","info":{"ipAddress":"10.0.6.14","authLevel":"0"}}],"result":"SUCCESSFUL","realm":"/","component":"Authentication","source":"audit","topic":"authentication","level":"INFO","_eventId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-239"} {"timestamp":"2022-08-07T16:21:14.806Z","eventName":"AM-ACCESS-OUTCOME","transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-228","trackingIds":["f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-231"],"userId":"id=amadmin,ou=user,ou=am-config","client":{"ip":"10.0.6.14","port":41664},"http":{"request":{"secure":true,"method":"POST","path":"https://xlou-sp.xlou-cdm.perf.freng.org/am/json/authenticate","headers":{"accept":["*/*"],"accept-api-version":["protocol=1.0,resource=2.0"],"content-type":["application/json"],"host":["xlou-sp.xlou-cdm.perf.freng.org"],"user-agent":["python-requests/2.26.0"],"x-forwarded-for":["35.212.145.225"],"x-forwarded-host":["xlou-sp.xlou-cdm.perf.freng.org"],"x-forwarded-port":["443"],"x-forwarded-proto":["https"],"x-real-ip":["35.212.145.225"],"x-request-id":["0c4f900d544d88d4117468c571875c45"],"x-scheme":["https"]}}},"response":{"status":"SUCCESSFUL","statusCode":"200","elapsedTime":103,"elapsedTimeUnits":"MILLISECONDS"},"realm":"/","component":"Authentication","source":"audit","topic":"access","level":"INFO","_eventId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-241"} 10.0.6.14 - - [07/Aug/2022:16:21:14 +0000] "POST /am/json/authenticate?realm=/ HTTP/1.1" 200 167 106ms 10.0.7.1 - - [07/Aug/2022:16:21:22 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 2ms {"timestamp":"2022-08-07T16:21:27.892Z","level":"DEBUG","thread":"http-nio-8081-exec-3","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-248"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-07T16:21:27.892Z","level":"DEBUG","thread":"http-nio-8081-exec-3","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-248"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-07T16:21:27.895Z","eventName":"AM-LOGIN-MODULE-COMPLETED","transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-248","trackingIds":["f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-251"],"userId":"id=amadmin,ou=user,ou=am-config","principal":["amadmin"],"entries":[{"moduleId":"DataStore","info":{"authControlFlag":"REQUIRED","moduleClass":"DataStore","ipAddress":"10.0.6.14","authLevel":"0"}}],"result":"SUCCESSFUL","realm":"/","component":"Authentication","source":"audit","topic":"authentication","level":"INFO","_eventId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-253"} {"timestamp":"2022-08-07T16:21:27.906Z","eventName":"AM-SESSION-CREATED","transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-248","trackingIds":["f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-251"],"userId":"id=amadmin,ou=user,ou=am-config","runAs":"id=amadmin,ou=user,ou=am-config","objectId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-251","operation":"CREATE","realm":"/","component":"Session","source":"audit","topic":"activity","level":"INFO","_eventId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-257"} {"timestamp":"2022-08-07T16:21:27.906Z","eventName":"AM-LOGIN-COMPLETED","transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-248","trackingIds":["f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-251"],"userId":"id=amadmin,ou=user,ou=am-config","principal":["amadmin"],"entries":[{"moduleId":"DataStore","info":{"ipAddress":"10.0.6.14","authLevel":"0"}}],"result":"SUCCESSFUL","realm":"/","component":"Authentication","source":"audit","topic":"authentication","level":"INFO","_eventId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-259"} {"timestamp":"2022-08-07T16:21:27.908Z","eventName":"AM-ACCESS-OUTCOME","transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-248","trackingIds":["f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-251"],"userId":"id=amadmin,ou=user,ou=am-config","client":{"ip":"10.0.6.14","port":41786},"http":{"request":{"secure":true,"method":"POST","path":"https://xlou-sp.xlou-cdm.perf.freng.org/am/json/authenticate","headers":{"accept":["*/*"],"accept-api-version":["protocol=1.0,resource=2.0"],"content-type":["application/json"],"host":["xlou-sp.xlou-cdm.perf.freng.org"],"user-agent":["python-requests/2.26.0"],"x-forwarded-for":["35.212.145.225"],"x-forwarded-host":["xlou-sp.xlou-cdm.perf.freng.org"],"x-forwarded-port":["443"],"x-forwarded-proto":["https"],"x-real-ip":["35.212.145.225"],"x-request-id":["696d439778adb67a93dd91b263557980"],"x-scheme":["https"]}}},"response":{"status":"SUCCESSFUL","statusCode":"200","elapsedTime":17,"elapsedTimeUnits":"MILLISECONDS"},"realm":"/","component":"Authentication","source":"audit","topic":"access","level":"INFO","_eventId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-261"} {"timestamp":"2022-08-07T16:21:28.144Z","level":"DEBUG","thread":"http-nio-8081-exec-4","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-262"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-07T16:21:28.144Z","level":"DEBUG","thread":"http-nio-8081-exec-4","mdc":{"transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-262"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-07T16:21:28.170Z","eventName":"AM-ACCESS-OUTCOME","transactionId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-262","trackingIds":["f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-251"],"userId":"id=amadmin,ou=user,ou=am-config","client":{"ip":"10.0.6.14","port":41788},"server":{"ip":"10.0.7.137","port":8081},"http":{"request":{"secure":true,"method":"GET","path":"https://xlou-sp.xlou-cdm.perf.freng.org/am/json/serverinfo/version","headers":{"accept":["*/*"],"accept-api-version":["protocol=2.1"],"host":["xlou-sp.xlou-cdm.perf.freng.org"],"user-agent":["python-requests/2.26.0"],"x-forwarded-for":["35.212.145.225"],"x-forwarded-host":["xlou-sp.xlou-cdm.perf.freng.org"],"x-forwarded-port":["443"],"x-forwarded-proto":["https"],"x-real-ip":["35.212.145.225"],"x-request-id":["b6ad0ca5e145e1576de5a1b8b80583ad"],"x-scheme":["https"]}}},"request":{"protocol":"CREST","operation":"READ"},"response":{"status":"SUCCESSFUL","statusCode":"","elapsedTime":29,"elapsedTimeUnits":"MILLISECONDS","detail":{"revision":"-340041102"}},"realm":"/","component":"Server Info","source":"audit","topic":"access","level":"INFO","_eventId":"f02fe1ce-cdf8-4bac-afa4-2f9ff97f4aed-266"} 10.0.6.22 - - [07/Aug/2022:16:21:23 +0000] "GET /am/json/metrics/prometheus HTTP/1.1" 200 350131 36ms 10.0.6.14 - - [07/Aug/2022:16:21:27 +0000] "POST /am/json/authenticate?realm=/ HTTP/1.1" 200 167 18ms 10.0.6.14 - - [07/Aug/2022:16:21:28 +0000] "GET /am/json/serverinfo/version HTTP/1.1" 200 277 34ms 10.0.7.1 - - [07/Aug/2022:16:21:32 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 3ms 10.0.7.1 - - [07/Aug/2022:16:21:32 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 3ms 10.0.7.1 - - [07/Aug/2022:16:21:42 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 2ms