Logging levels not obeying logger.ini?

Hi,

I’m trying to turn down the logging verbosity a bit via /opt/kaltura/app/configurations/logger.ini to avoid some of the debug information that’s being thrown there. I did this without issue in CE5, but it seems with the new version the default priority is already set to 4, which should be WARN. However I’m seeing plenty of DEBUG level statements in the log file.

Is there something I’m missing here? I’ve restarted apache on the single front end I’m working on in case I had changed it and not remembered… That didn’t have any effect.

Thanks in advance!

Hi Dave,

Can you please paste you logger.ini here and also sample DEBUG lines and the log file[s] in question?

Hey Jess,

My apologies. when pasting my logger.ini file, I finally saw that the priority setting at the top was commented out. I think that had an effect here.

Here are some log entries:

2017-08-17 10:55:31 [0.000480] [10.4.16.32] [301684855] [145] [API] [KalturaStatement->execute] DEBUG: Sql took - 0.00037407875061035 seconds
2017-08-17 10:55:31 [0.000575] [10.4.16.32] [301684855] [146] [API] [kJobsCacher::getQueue] INFO: No cached queue for worker Id [320]
2017-08-17 10:55:31 [0.000187] [10.4.16.32] [301684855] [147] [API] [DbManager::connectFallbackLogic] DEBUG: connected to propel2
2017-08-17 10:55:31 [0.000320] [10.4.16.32] [301684855] [148] [API] [KalturaStatement->execute] DEBUG: /* omapi-kltfe01[301684855][propel2] */ SELECT COUNT(*) FROM `batch_job_lock` WHERE batch_job_lock.JOB_TYPE='10029' AND (batch_job_
lock.STATUS='8' AND ((batch_job_lock.STATUS='0' OR (
                                                        batch_job_lock.STATUS IN (0,1,2,3,4,9)
                                                AND     batch_job_lock.EXPIRATION <= '2017-08-17 10:55:31'
                                        )) OR (
                                                batch_job_lock.STATUS IN (9, 8)
                                                AND batch_job_lock.START_AT <= '2017-08-17 10:55:31'
                                        ))) AND (
                                                                batch_job_lock.EXECUTION_ATTEMPTS <= 3
                                                                OR
                                                                batch_job_lock.EXECUTION_ATTEMPTS IS NULL
                                                        ) AND batch_job_lock.DC='0'
2017-08-17 10:55:31 [0.000497] [10.4.16.32] [301684855] [149] [API] [KalturaStatement->execute] DEBUG: Sql took - 0.00039196014404297 seconds

Here is logger.ini from /opt/kaltura/app/configurations.

; #################################################
; #
; # Logging level, format, log files paths and stream outputs configuration
; #
; #################################################


[all]
eventItems.timestamp = LogTime
eventItems.uniqueId = UniqueId
eventItems.sessionIndex = SessionIndex
eventItems.logMethod = LogMethod
eventItems.logIp = LogIp
eventItems.logDuration = LogDuration

writers.stream.name = Zend_Log_Writer_Stream
writers.stream.formatters.simple.name = Zend_Log_Formatter_Simple
writers.stream.formatters.simple.format = "%timestamp% [%logDuration%] [%logIp%] [%uniqueId%] [%sessionIndex%] [%context%] [%logMethod%] %priorityName%: %message%"
;writers.stream.filters.priority.name = Zend_Log_Filter_Priority
;writers.stream.filters.priority.priority = 4
;writers.stream.filters.priority.operator = "<="
writers.stream.filters.type.name = KalturaLogFilterType
writers.stream.filters.type.type =


[client : all]
writers.stream.formatters.simple.format = "%timestamp% [%logDuration%] [%uniqueId%] [%sessionIndex%] [%context%] [%logMethod%] %priorityName%: %message%"


[ps2 : all]
writers.stream.stream = /opt/kaltura/log/kaltura_prod.log


[ps2_dev : all]
writers.stream.stream = /opt/kaltura/log/kaltura_dev.log


[api_v3 : all]
enableTests = 0

writers.stream.stream = /opt/kaltura/log/kaltura_api_v3.log

writers.analytics.stream = /opt/kaltura/log/kaltura_api_v3_analytics.log
writers.analytics.name = Zend_Log_Writer_Stream
writers.analytics.formatters.simple.name = Zend_Log_Formatter_Simple
writers.analytics.formatters.simple.format = "%timestamp% [%logIp%] [%uniqueId%]: %message%"
writers.analytics.filters.priority.name = Zend_Log_Filter_Priority
writers.analytics.filters.priority.priority=4
writers.analytics.filters.priority.operator = "<="
writers.analytics.filters.type.name = KalturaLogFilterType
writers.analytics.filters.type.type = LOG_TYPE_ANALYTICS

writers.tests.stream = /opt/kaltura/log/kaltura_api_v3_tests.log
writers.tests.name = Zend_Log_Writer_Stream
writers.tests.formatters.simple.name = Zend_Log_Formatter_Simple
writers.tests.formatters.simple.format = "%timestamp% [%logIp%] [%uniqueId%] [%context%] [%logMethod%] %priorityName%: %message%"
writers.tests.filters.priority.name = Zend_Log_Filter_Priority
writers.tests.filters.priority.priority=4
writers.tests.filters.priority.operator = "<="
writers.tests.filters.type.name = KalturaLogFilterType
writers.tests.filters.type.type = LOG_TYPE_TESTS


[admin : client]
writers.stream.stream = /opt/kaltura/log/kaltura_admin.log


[scripts : all]
writers.stream.stream = php://output
writers.stream.formatters.simple.format = "%timestamp% [%logMethod%] %priorityName%: %message%"

writers.scripts.stream = /opt/kaltura/log/kaltura_scripts.log
writers.scripts.name = Zend_Log_Writer_Stream
writers.scripts.formatters.simple.name = Zend_Log_Formatter_Simple
writers.scripts.formatters.simple.format = "%timestamp% [%uniqueId%] [%context%] [%logMethod%] %priorityName%: %message%"
writers.scripts.filters.priority.name = Zend_Log_Filter_Priority
writers.scripts.filters.priority.priority=4
writers.scripts.filters.priority.operator = "<="


[generator : all]
writers.stream.stream = php://output
writers.stream.formatters.simple.format = "%timestamp% [%logMethod%] %priorityName%: %message%"
writers.stream.filters.priority.name = Zend_Log_Filter_Priority
writers.stream.filters.priority.priority=4
writers.stream.filters.priority.operator = "<="


[batch : client]
writers.stream.stream = php://output

writers.stderr.stream = php://stderr
writers.stderr.name = Zend_Log_Writer_Stream
writers.stderr.formatters.simple.name = Zend_Log_Formatter_Simple
writers.stderr.formatters.simple.format = "%timestamp% [%logDuration%] [%uniqueId%] [%sessionIndex%] [%context%] [%logMethod%] %priorityName%: %message%"
writers.stderr.filters.priority.name = Zend_Log_Filter_Priority
writers.stderr.filters.priority.priority=4
writers.stderr.filters.priority.operator = "<="
writers.stderr.filters.type.name = KalturaLogFilterType
writers.stderr.filters.type.type = STANDARD_ERROR


[batch_scheduler : batch]
writers.stream.stream = /opt/kaltura/log/kaltura_batch.log


[tests : client]
writers.stream.stream = /opt/kaltura/log/kaltura_tests.log
writers.stream.filters.priority.name = Zend_Log_Filter_Priority
writers.stream.filters.priority.priority=4
writers.stream.filters.priority.operator = "<="

writers.tests.stream = php://output
writers.tests.name = Zend_Log_Writer_Stream
writers.tests.formatters.simple.name = Zend_Log_Formatter_Simple
writers.tests.formatters.simple.format = "%timestamp% [%logMethod%] %priorityName%: %message%"
writers.tests.filters.priority.name = Zend_Log_Filter_Priority
writers.tests.filters.priority.priority=4
writers.tests.filters.priority.operator = "<="


[deploy : all]
writers.stream.stream = /opt/kaltura/log/kaltura_deploy.log
writers.stream.filters.priority.name = Zend_Log_Filter_Priority
writers.stream.filters.priority.priority=4
writers.stream.filters.priority.operator = "<="

writers.deploy.stream = php://output
writers.deploy.name = Zend_Log_Writer_Stream
writers.deploy.formatters.simple.name = Zend_Log_Formatter_Simple
writers.deploy.formatters.simple.format = "%timestamp% [%logMethod%] %priorityName%: %message%"
writers.deploy.filters.priority.name = Zend_Log_Filter_Priority
writers.deploy.filters.priority.priority=4
writers.deploy.filters.priority.operator = "<="

[var : client]
writers.stream.stream = /opt/kaltura/log/kaltura_multi_publishers.log

[hosted_pages : client]
writers.stream.stream = /opt/kaltura/log/kaltura_hosted_pages.log