BIND 10 #1024: increase logging verbosity in unittests

BIND 10 Development do-not-reply at isc.org
Thu Jul 7 21:22:47 UTC 2011


#1024: increase logging verbosity in unittests
-------------------------------------+-------------------------------------
                   Reporter:         |                 Owner:  jreed
  jinmei                             |                Status:  reviewing
                       Type:  task   |             Milestone:
                   Priority:  major  |  Sprint-20110712
                  Component:         |            Resolution:
  logging                            |             Sensitive:  0
                   Keywords:         |           Sub-Project:  Core
            Defect Severity:  N/A    |  Estimated Difficulty:  0.5
Feature Depending on Ticket:         |           Total Hours:  0
        Add Hours to Ticket:  0      |
                  Internal?:  0      |
-------------------------------------+-------------------------------------

Comment (by jreed):

 The OutputOptionTest still logs:

 {{{
 2011-07-07 16:10:47.981 WARN  [bind10.log] LOG_DUPLICATE_MESSAGE_ID
 duplicate message ID (LOG_DUPLICATE_NAMESPACE) in compiled code
 }}}
 And:
 {{{
 [ RUN      ] LoggerSupportTest.LoggingInitializationCheck
 2011-07-07 16:10:47.987 INFO  [bind10.test] LOG_INPUT_OPEN_FAIL unable to
 open message file %1 for input: %2
 [       OK ] LoggerSupportTest.LoggingInitializationCheck (0 ms)
 }}}

 And
 {{{
 [ RUN      ] LoggerLevel.getSeverity
 2011-07-07 16:10:48.011 ERROR [bind10.log] LOG_BAD_SEVERITY unrecognized
 log severity: some bad value
 2011-07-07 16:10:48.011 ERROR [bind10.log] LOG_BAD_SEVERITY unrecognized
 log severity:
 [       OK ] LoggerLevel.getSeverity (1 ms)
 }}}
 And
 {{{
 [ RUN      ] LoggerLevelImplTest.FromString
 2011-07-07 16:10:48.012 WARN  [bind10.log] LOGIMPL_BELOW_MIN_DEBUG debug
 level of -5 is too low and will be set to the minimum of 0
 2011-07-07 16:10:48.012 WARN  [bind10.log] LOGIMPL_BELOW_MIN_DEBUG debug
 level of -4 is too low and will be set to the minimum of 0
 2011-07-07 16:10:48.012 WARN  [bind10.log] LOGIMPL_BELOW_MIN_DEBUG debug
 level of -3 is too low and will be set to the minimum of 0
 2011-07-07 16:10:48.012 WARN  [bind10.log] LOGIMPL_BELOW_MIN_DEBUG debug
 level of -2 is too low and will be set to the minimum of 0
 2011-07-07 16:10:48.012 WARN  [bind10.log] LOGIMPL_BELOW_MIN_DEBUG debug
 level of -1 is too low and will be set to the minimum of 0
 2011-07-07 16:10:48.012 WARN  [bind10.log] LOGIMPL_ABOVE_MAX_DEBUG debug
 level of 100 is too high and will be set to the maximum of 99
 2011-07-07 16:10:48.012 WARN  [bind10.log] LOGIMPL_ABOVE_MAX_DEBUG debug
 level of 101 is too high and will be set to the maximum of 99
 2011-07-07 16:10:48.012 WARN  [bind10.log] LOGIMPL_ABOVE_MAX_DEBUG debug
 level of 102 is too high and will be set to the maximum of 99
 2011-07-07 16:10:48.012 WARN  [bind10.log] LOGIMPL_ABOVE_MAX_DEBUG debug
 level of 103 is too high and will be set to the maximum of 99
 [       OK ] LoggerLevelImplTest.FromString (1 ms)
 }}}

 And
 {{{
 Running test: log_test.py
 .......2011-07-07 16:11:19.329 INFO  [root.log] LOG_READING_LOCAL_FILE
 reading local message file /no/such/file
 2011-07-07 16:11:19.329 ERROR [root.log] LOG_INPUT_OPEN_FAIL unable to
 open message file /no/such/file for input: No such file or directory
 ..
 }}}
 And
 {{{
 [ RUN      ] IOFetchTest.TcpTimeout
 2011-07-07 16:11:28.443 ERROR [unittest.asiolink] ASIODNS_OPEN_SOCKET
 error 61 opening TCP socket to 127.0.0.1(5301)
 [       OK ] IOFetchTest.TcpTimeout (4011 ms)
 }}}
 And
 {{{
 Running test: bind10_test.py
 .......2011-07-07 16:12:28.408 WARN  [b10-boss.boss]
 BIND10_PROCESS_ENDED_WITH_EXIT_STATUS process b10-auth (PID 5) terminated,
 exit status = 0
 }}}

 And
 {{{

 Running test: cmdctl_test.py
 2011-07-07 16:12:42.543 ERROR [b10-cmdctl.cmdctl] CMDCTL_BAD_CONFIG_DATA
 error in config data: unknown config item: non-exist
 2011-07-07 16:12:42.545 ERROR [b10-cmdctl.cmdctl] CMDCTL_BAD_CONFIG_DATA
 error in config data: the file doesn't exist: /user/non-exist_folder
 2011-07-07 16:12:42.546 ERROR [b10-cmdctl.cmdctl] CMDCTL_BAD_CONFIG_DATA
 error in config data: the file doesn't exist: /user/non-exist_folder
 2011-07-07 16:12:42.547 ERROR [b10-cmdctl.cmdctl] CMDCTL_BAD_CONFIG_DATA
 error in config data: Invalid accounts file: [Errno 2] No such file or
 directory: '/user/non-exist_folder'
 2011-07-07 16:12:42.673 ERROR [b10-cmdctl.cmdctl] CMDCTL_BAD_CONFIG_DATA
 error in config data: Invalid accounts file: list index out of range
 .......2011-07-07 16:12:42.701 INFO  [b10-cmdctl.cmdctl]
 CMDCTL_BAD_PASSWORD bad password for user: root
 ....2011-07-07 16:12:42.707 INFO  [b10-cmdctl.cmdctl] CMDCTL_NO_SUCH_USER
 username not found in user database: root
 ................2011-07-07 16:12:42.946 ERROR [b10-cmdctl.cmdctl]
 CMDCTL_USER_DATABASE_READ_ERROR failed to read user database file /local
 /not-exist: [Errno 2]
 No such file or directory: '/local/not-exist'
 2011-07-07 16:12:42.946 ERROR [b10-cmdctl.cmdctl]
 CMDCTL_NO_USER_ENTRIES_READ failed to read user information, all users
 will be denied
 .2011-07-07 16:12:42.957 INFO  [b10-cmdctl.cmdctl]
 CMDCTL_SSL_SETUP_FAILURE_USER_DENIED failed to create an SSL connection
 (user denied): key file '../cmdctl-keyfile' doesn't exist
 }}

 And

 {{{

 Running test: xfrout_test.py
 ......2011-07-07 16:12:46.914 ERROR [b10-xfrout.xfrout]
 XFROUT_REMOVE_OLD_UNIX_SOCKET_FILE_ERROR error removing unix socket file
 /tmp/tmpZ738Hb: [Errno 1] Operation not permitted: '/tmp/tmpZ738Hb'
 .2011-07-07 16:12:46.918 ERROR [b10-xfrout.xfrout]
 XFROUT_UNIX_SOCKET_FILE_IN_USE another xfrout process seems to be using
 the unix socket file temp.sock.file
 ....2011-07-07 16:12:46.923 INFO  [b10-xfrout.xfrout] XFROUT_NEW_CONFIG
 Update xfrout configuration
 2011-07-07 16:12:46.923 INFO  [b10-xfrout.xfrout] XFROUT_NEW_CONFIG_DONE
 Update xfrout configuration done
 2011-07-07 16:12:46.924 INFO  [b10-xfrout.xfrout] XFROUT_NEW_CONFIG Update
 xfrout configuration
 2011-07-07 16:12:46.924 INFO  [b10-xfrout.xfrout] XFROUT_NEW_CONFIG_DONE
 Update xfrout configuration done
 2011-07-07 16:12:46.924 INFO  [b10-xfrout.xfrout] XFROUT_NEW_CONFIG Update
 xfrout configuration
 2011-07-07 16:12:46.929 ERROR [b10-xfrout.xfrout]
 XFROUT_BAD_TSIG_KEY_STRING bad TSIG key string:
 bad..example.com:SFuWd/q99SzF8Yzd1QbB9g==
 2011-07-07 16:12:46.929 INFO  [b10-xfrout.xfrout] XFROUT_NEW_CONFIG_DONE
 Update xfrout configuration done
 ....2011-07-07 16:12:46.931 ERROR [b10-xfrout.xfrout]
 XFROUT_PARSE_QUERY_ERROR error parsing query: Malformed DNS message (short
 length): 7
 .2011-07-07 16:12:46.931 INFO  [b10-xfrout.xfrout]
 XFROUT_AXFR_TRANSFER_STARTED transfer of zone example.com/IN has started
 2011-07-07 16:12:46.931 INFO  [b10-xfrout.xfrout]
 XFROUT_AXFR_TRANSFER_DONE transfer of example.com/IN complete
 .2011-07-07 16:12:46.932 INFO  [b10-xfrout.xfrout]
 XFROUT_AXFR_TRANSFER_FAILED transfer of example.com/IN failed, rcode:
 NOTAUTH
 }}}

 I guess I should not have copied all these here... I realize now these are
 all not DEBUG?  Maybe they should be excluded too?

 I core dump problem is gone. Thanks.

-- 
Ticket URL: <http://bind10.isc.org/ticket/1024#comment:11>
BIND 10 Development <http://bind10.isc.org>
BIND 10 Development


More information about the bind10-tickets mailing list