BIND 10 #1629: logging with garbage in it

[BIND 10 Development]

#1629: logging with garbage in it
--------------------------------+-----------------------------------------
            Reporter:  jreed    |                        Owner:
                Type:  defect   |                       Status:  new
            Priority:  major    |                    Milestone:  New Tasks
           Component:  logging  |                     Keywords:
           Sensitive:  0        |              Defect Severity:  N/A
         Sub-Project:  DNS      |  Feature Depending on Ticket:
Estimated Difficulty:  0        |          Add Hours to Ticket:  0
         Total Hours:  0        |                    Internal?:  0
--------------------------------+-----------------------------------------
 See https://lists.isc.org/pipermail/bind10-dev/2011-December/002901.html

 There was a timeout in a lettuce test on December 6.

  Wait for bind10 stderr message CMDCTL_STARTED

 The stderr output has garbage in it. (I piped through cat -v so I could
 paste it.)

 {{{

 2011-12-06 02:13:57.560 DEBUG [b10-stats-httpd.stats-httpd]
 STATHTTPD_STARTING_CC_SESSION starting cc session
 2011-12-06 02:13:57.567 DEBUG [b10-stats-httpd.config]
 CONFIG_LOG_IGNORE_EXPLICIT ignoring logging configuration for
 explicitly-named logger auth
 2011-12-06 02:13:57.579 INFO  [b10-xfrout.xfrout] XFROUT_NEW_CONFIG
 Update xfrout configuration
 2011-12-06 02:13:57.579
 ^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@INFO^@ EBUG^@
 [^@^@b10-xfrout.xfrout^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@]
 ^@^@XFROUT_NEW_CONFIG_DONE Update xfrout configuration
 done27.0.0.1:47805^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@
 ^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@
 ^@2011-12-06 02:13:57.585 INFO  [b10-stats-httpd.stats-httpd]
 STATHTTPD_STARTED listening on 127.0.0.1#8000
 2011-12-06 02:14:07.808 INFO  [b10-boss.boss] BIND10_RECEIVED_SIGNAL
 received signal SIGTERM

 }}}

 Today on same system (macmini) I saw another corrupted log file (seen in
 failing systest):


 {{{
 macmini:system jreed$ grep -a -1 '[[:cntrl:]]' ./ixfr/in-2/nsx2/bind10.run
 | cat -v
 2012-01-25 03:58:52.408 DEBUG [b10-cmdctl.cmdctl] CMDCTL_STARTED cmdctl is
 listening for connections on 127.0.0.1:8080
 2012-01-25 03:58:52.429 012-01-25 03:58:52.428^@IINFO ^@^@^@ ^@
 [^@^@b10-xfrout.xfrouttats-httpd^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@]
 ^@^@XFROUT_NEW_CONFIG Update xfrout
 configuration^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@
 ^@2012-01-25 03:58:52.429 INFO  [b10-xfrout.xfrout] XFROUT_NEW_CONFIG_DONE
 Update xfrout configuration done
 2012-01-25 03:59:07.946 INFO  [b10-xfrin.xfrin] XFRIN_XFR_TRANSFER_STARTED
 IXFR transfer of zone example/IN started

 }}}

 Looks like corruption in same place.

-- 
Ticket URL: <http://bind10.isc.org/ticket/1629>
BIND 10 Development <http://bind10.isc.org>
BIND 10 Development