CPS-10 Add ONAP compliant logging config

CPS-10: Add ONAP compliant logging configClosed



Sources

ONAP Application Logging Specification v1.2 (Casablanca)#HowtoLog

Issues/Decisions

#

Issue/Decision

Notes

Decision

#

Issue/Decision

Notes

Decision

1

Are the logging guidelines set by the Logging Enhancement Project suitable for a cloud environment?





2

Do we need a second appender for errors?

  • one file only

  • Later we will have a file to track who is accessing it, who is registering for the access...but we are not at that point yet

  • Within the log folder we could have multiple files for:

    • debug.log

    • audit.log

    • metric.log

    • error.log

    Logs will be archived each day.

Meeting Notes 11/12/20 

https://docs.onap.org/projects/onap-logging-analytics/en/latest/Logging_Enhancements_Project/logging_enhancements_project.html#id33

The logging enhancement team has proposed to split the log to multiple files: 

  • debug.log

  • audit.log

  • metric.log

  • error.log



Toine has suggested we follow this approach but exclude error logging. 



Before making anymore decisions we will investigate this project as we have some concerns about the logging standards in the logging enhancement project.

3

The application should only logs to stdout and not in files?

  • By doing so we can leverage Kubernetes standard logging design.

  • If logging to files is provided, It would be good to also have a way to disable it to avoid file space management questions on worker nodes or pvc depending where logs files would be kept.

  • For monitoring, it is more complex to operate applications that are each one using specific logs files.

  • By having all applications logging to stdout, all logs can be collected in a common standard way and published and re-used anywhere that is convenient for any operations team (using filebeat or elk stack for example).

    See https://kubernetes.io/docs/concepts/cluster-administration/logging/ 



  • Whether user will be using Kubernetes / Azure or single manual deployment, features capability should be the same. We should not enforce to use anything specific to achieve the same service solution.

  • Many company enforce to  have those separate type of log files by security policy. Usually they store the audits logs ones.
    With log properties, you always can change the level log. And also alterate/disable the logs appenders at runtime.

Meeting Notes 11/12/20 

  • Everything a containerized application writes to stdout and stderr is handled and redirected somewhere by a container engine. For example, the Docker container engine redirects those two streams to a logging driver, which is configured in Kubernetes to write to a file in json format. - use a log rotation script

  • We want the logging to be easily configurable and allow for different implementations on how to collect logs. We will expose this configuration in the helm charts.  

  • Our default logging will be std out as it is easily configurable. 





4

Is the file location ok?

 ../log/${logName}.log



I think this is ok. Logs will be placed in pods once deployed. 

I think we will need to set a property in our SpringBootApplication class for the log dir.



5

Disk space

<property name="maxFileSize" value="20MB" />

Once the log reaches this value it is zipped.



6

What kind of message should go with which level for logging?

See 'Summary of each logging level' below

Could consider a (CPSException) property that differentiate between error/warning and even fatal if we want?

Business logic exceptions will be logged in an audit log (future)

For now we will add them at a lower logging level if you feel like the log is helpful - it depends on case

e.g. If the user tries to add an anchor with no dataspace we will log "Dataspace does not exist". This will be logged in the place it is handled. 

Sonar advises to either log the exception or throw it.

You should log the original exception if it provides more information. 

It is up to the consumer of the java API if they want to log the exception or not. 

In the REST API when we catch an exception we should log it as we lose a lot of information when we convert it into a HTTP response code.

For business exceptions we will use error level but we will add configuration so that it is not written to the console by default.

7

Where exactly to log error, at source or a common central place?

  1.  

    1. Prevent same error being logged may times (some duplication might be unavoidable though)

    2. Consider that REST layer is optional (so that is why the current commit is not a good solution for centralized logging)

It should be logged in the place the error is generated.

8

How should we format our logs?

logger.debug("No of Orders " + noOfOrder + " for client : " + client); 
logger.debug("No of Executions {} for clients:{}", noOfOrder , client); 

logger.debug("No of Executions {} for clients:{}", noOfOrder , client); 

9

Should we use is log.isDebugEnabled()?

I think we should if the cost of performing the log is expensive - for example if we need to build a parameter in the log

yes, we will check log level.

10

What kind of information to log?

  1. Never log sensitive information as plain text 

  2. log all important information that is necessary to debug or troubleshoot a problem if it happens.

  3. Always log decision making statements e.g. the application loads some settings from a preference file and is unable to find the file 

See CPS Logging Guidelines 



Summary of each logging level

Log Level

Importance

Log Level

Importance

Fatal

One or more key business functionalities are not working and the whole system doesn’t fulfill the business functionalities.

Error

One or more functionalities are not working, preventing some functionalities from working correctly.

Warn

Unexpected behavior happened inside the application, but it is continuing its work and the key business features are operating as expected.

Info

An event happened, the event is purely informative and can be ignored during normal operations.

Debug

A log level used for events considered to be useful during software debugging when more granular information is needed.

Trace

A log level describing events showing step by step execution of your code that can be ignored during the standard operation, but may be useful during extended debugging sessions.

Appenders used by ONAP Projects 

Types of EELF Logs

EELF guidelines stipulate that an application should output log records to four separate files:

  1. audit

  2. metrics

  3. error

  4. debug





Excerpt from ONAP Application Logging Specification v1.3 (Frankfurt)

ONAP Application Logging Specification v1.3 (Frankfurt)

Current Log Config



Dependencies (pom)
<dependency> <!-- For logging --> <groupId>org.slf4j</groupId> <artifactId>slf4j-api</artifactId> </dependency>



Logging level is configured in the application.yaml
... logging: level: org: springframework: INFO



Logback configuration is done in logback-spring.xml
<configuration scan="true" debug="false"> <include resource="org/springframework/boot/logging/logback/base.xml" /> <property name="queueSize" value="256" /> <property name="maxFileSize" value="20MB" /> <property name="maxHistory" value="30" /> <property name="totalSizeCap" value="20MB" /> <!-- log file names --> <property name="logName" value="cps" /> <property name="currentTimeStamp" value="%d{"yyyy-MM-dd'T'HH:mm:ss.SSSXXX",UTC}"/> <property name="debugPattern" value="%d{yyyy-MM-dd'T'HH:mm:ss.SSSXXX}|%thread|%X{RequestID}| %logger{50} - %msg%n" /> <appender name="Debug" class="ch.qos.logback.core.rolling.RollingFileAppender"> <file>../log/${logName}.log</file> <rollingPolicy class="ch.qos.logback.core.rolling.SizeAndTimeBasedRollingPolicy"> <fileNamePattern>${logName}.%d{yyyy-MM-dd}.%i.log.zip </fileNamePattern> <maxFileSize>${maxFileSize}</maxFileSize> <maxHistory>${maxHistory}</maxHistory> <totalSizeCap>${totalSizeCap}</totalSizeCap> </rollingPolicy> <encoder> <pattern>${debugPattern}</pattern> </encoder> </appender> <appender name="asyncDebug" class="ch.qos.logback.classic.AsyncAppender"> <queueSize>256</queueSize> <appender-ref ref="Debug" /> <includeCallerData>true</includeCallerData> </appender> <logger name="org.onap.cps" level="DEBUG" additivity="false"> <appender-ref ref="asyncDebug" /> </logger> <root level="INFO"> <appender-ref ref="asyncDebug" /> </root> </configuration>



To generate logging add the following parameter and imports



Log files are generated in your development workspace folder - ../log/${logName}.log

2020-12-09T11:31:51.792Z|qtp1971152916-37|| org.onap.cps.rest.controller.CpsRestController - error message
2020-12-09T11:31:51.792Z|qtp1971152916-37|| org.onap.cps.rest.controller.CpsRestController - debug message

cps.log