Versions Compared

Key

  • This line was added.
  • This line was removed.
  • Formatting was changed.

...

Troubleshooting the log configuration

Expand
titleVerify from the console log the following kind of information being present when SSO authentication server (uas) is being started

Windows installation example (the paths differ in the linux installation)

Code Block
05:01:09,309 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
05:01:09,309 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [file:/C:/Program%20Files/Ubisecure/ubilogin-sso/tomcat/webapps/uas/WEB-INF/classes/logback.xml]
05:01:09,371 |-INFO in ch.qos.logback.core.joran.util.ConfigurationWatchListUtil@1df5c1f3 - Adding [file:/C:/Program%20Files/Ubisecure/ubilogin-sso/ubilogin/custom/logging/include-logback.xml] to configuration watch list.
05:01:09,403 |-INFO in ch.qos.logback.core.joran.action.ConversionRuleAction - registering conversion word diagex with class [com.ubisecure.common.logging.LogExceptionConverter]
05:01:09,403 |-INFO in ch.qos.logback.core.joran.action.ConversionRuleAction - registering conversion word diagmarker with class [com.ubisecure.common.logging.MarkerConverter]
05:01:09,403 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
05:01:09,403 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [CONSOLE]
05:01:09,403 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
05:01:09,434 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
05:01:09,449 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE]
05:01:09,449 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
05:01:09,637 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@2143236581 - No compression will be used
05:01:09,637 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@2143236581 - Will use the pattern C:/Program Files/Ubisecure/ubilogin-sso/ubilogin/logs/sso_diag.%d{yyyy-MM-dd}.log for the active file
05:01:09,637 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - The date pattern is 'yyyy-MM-dd' from file name pattern 'C:/Program Files/Ubisecure/ubilogin-sso/ubilogin/logs/sso_diag.%d{yyyy-MM-dd}.log'.
05:01:09,637 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Roll-over at midnight.
05:01:09,637 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Setting initial period to Mon Oct 03 05:01:09 UTC 2022
05:01:09,637 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: C:/Program Files/Ubisecure/ubilogin-sso/ubilogin/logs/sso_diag.2022-10-03.log
05:01:09,637 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [null]
05:01:09,637 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
05:01:09,637 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [AUDIT_FILE]
05:01:09,637 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
05:01:09,637 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1273758108 - No compression will be used
05:01:09,637 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1273758108 - Will use the pattern C:/Program Files/Ubisecure/ubilogin-sso/ubilogin/logs/uas3_audit.%d{yyyy-MM-dd}.log for the active file
05:01:09,637 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - The date pattern is 'yyyy-MM-dd' from file name pattern 'C:/Program Files/Ubisecure/ubilogin-sso/ubilogin/logs/uas3_audit.%d{yyyy-MM-dd}.log'.
05:01:09,637 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Roll-over at midnight.
05:01:09,637 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Setting initial period to Mon Oct 03 05:01:09 UTC 2022
05:01:09,637 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[AUDIT_FILE] - Active log file name: C:/Program Files/Ubisecure/ubilogin-sso/ubilogin/logs/uas3_audit.2022-10-03.log
05:01:09,637 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[AUDIT_FILE] - File property is set to [null]
05:01:09,653 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
05:01:09,653 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STATISTICS_FILE]
05:01:09,653 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
05:01:09,653 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@855663126 - No compression will be used
05:01:09,653 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@855663126 - Will use the pattern C:/Program Files/Ubisecure/ubilogin-sso/ubilogin/logs/uas3_statistics.%d{yyyy-MM-dd}.log for the active file
05:01:09,653 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - The date pattern is 'yyyy-MM-dd' from file name pattern 'C:/Program Files/Ubisecure/ubilogin-sso/ubilogin/logs/uas3_statistics.%d{yyyy-MM-dd}.log'.
05:01:09,653 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Roll-over at midnight.
05:01:09,653 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Setting initial period to Mon Oct 03 05:01:09 UTC 2022
05:01:09,653 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[STATISTICS_FILE] - Active log file name: C:/Program Files/Ubisecure/ubilogin-sso/ubilogin/logs/uas3_statistics.2022-10-03.log
05:01:09,653 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[STATISTICS_FILE] - File property is set to [null]
05:01:09,653 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
05:01:09,653 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [MANAGEMENT_AUDIT_FILE]
05:01:09,653 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
05:01:09,653 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@942307416 - No compression will be used
05:01:09,653 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@942307416 - Will use the pattern C:/Program Files/Ubisecure/ubilogin-sso/ubilogin/logs/management_audit.%d{yyyy-MM-dd}.log for the active file
05:01:09,653 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - The date pattern is 'yyyy-MM-dd' from file name pattern 'C:/Program Files/Ubisecure/ubilogin-sso/ubilogin/logs/management_audit.%d{yyyy-MM-dd}.log'.
05:01:09,653 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Roll-over at midnight.
05:01:09,653 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Setting initial period to Mon Oct 03 05:01:09 UTC 2022
05:01:09,653 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[MANAGEMENT_AUDIT_FILE] - Active log file name: C:/Program Files/Ubisecure/ubilogin-sso/ubilogin/logs/management_audit.2022-10-03.log
05:01:09,653 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[MANAGEMENT_AUDIT_FILE] - File property is set to [null]
05:01:09,653 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
05:01:09,653 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [PASSWORD_AUDIT_FILE]
05:01:09,653 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
05:01:09,668 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1956080095 - No compression will be used
05:01:09,668 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1956080095 - Will use the pattern C:/Program Files/Ubisecure/ubilogin-sso/ubilogin/logs/password_audit.%d{yyyy-MM-dd}.log for the active file
05:01:09,668 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - The date pattern is 'yyyy-MM-dd' from file name pattern 'C:/Program Files/Ubisecure/ubilogin-sso/ubilogin/logs/password_audit.%d{yyyy-MM-dd}.log'.
05:01:09,668 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Roll-over at midnight.
05:01:09,668 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Setting initial period to Mon Oct 03 05:01:09 UTC 2022
05:01:09,668 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[PASSWORD_AUDIT_FILE] - Active log file name: C:/Program Files/Ubisecure/ubilogin-sso/ubilogin/logs/password_audit.2022-10-03.log
05:01:09,668 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[PASSWORD_AUDIT_FILE] - File property is set to [null]
05:01:09,668 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.ubisecure.ubilogin.management] to INFO
05:01:09,668 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [MANAGEMENT_AUDIT_FILE] to Logger[com.ubisecure.ubilogin.management]
05:01:09,668 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to INFO
05:01:09,668 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [CONSOLE] to Logger[ROOT]
05:01:09,668 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [FILE] to Logger[ROOT]
05:01:09,668 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [AUDIT_FILE] to Logger[ROOT]
05:01:09,668 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STATISTICS_FILE] to Logger[ROOT]
05:01:09,668 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [PASSWORD_AUDIT_FILE] to Logger[ROOT]
05:01:09,668 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.ubisecure.saml2.metadata.URLMetadataLocator] to INFO
05:01:09,668 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.ubisecure.util.filter] to DEBUG
05:01:09,668 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
05:01:09,684 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6373088 - Registering current configuration as safe fallback point
05:01:09,293 |-INFO in ch.qos.logback.classic.servlet.LogbackServletContainerInitializer@2ac5a474 - Due to deployment instructions will NOT register an instance of class ch.qos.logback.classic.servlet.LogbackServletContextListener to the current web-app
2022-10-03 05:01:09,700 uas init INFO Ubilogin Authentication Server 9.1.0 starting 

Info

NOTE about SSO Tomcat console log (including System.out and System.err)

In linux environments console log is visible e.g. in /var/log/messages.

In Windows environments console log is disabled by default. In order to verify events in console log you need to do the following:

  1. Edit the C:\Program Files\Ubisecure\ubilogin-sso\ubilogin\config\tomcat\install-service.cmd script

  2. Replace the logger parameters for the service --StdOutput nul --StdError nul => --StdOutput auto --StdError auto (line 16)

    Code Block
    "%prunsrv.exe%" "//US/%TOMCAT_INSTANCENAME%" --LogPath "%CATALINA_HOME%\logs" --StdOutput auto --StdError auto
  3. Update and restart SSO service by executing C:\Program Files\Ubisecure\ubilogin-sso\ubilogin\config\tomcat\update.cmd script

  4. These logs files will be created in Tomcat logs folder C:\Program Files\Ubisecure\ubilogin-sso\tomcat\logs

    1. ubiloginserver-stdout.YYYY-MM-DD.log

    2. ubiloginserver-stderr.YYYY-MM-DD.log

...