BIND 10 #2900: Investigate and fix mixed log messages

[BIND 10 Development]

#2900: Investigate and fix mixed log messages
-------------------------------------+-------------------------------------
                   Reporter:  muks   |                 Owner:
                       Type:         |                Status:  new
  defect                             |             Milestone:  Next-Sprint-
                   Priority:         |  Proposed
  medium                             |              Keywords:
                  Component:         |             Sensitive:  0
  logging                            |           Sub-Project:  DNS
               CVSS Scoring:         |  Estimated Difficulty:  0
            Defect Severity:  N/A    |           Total Hours:  0
Feature Depending on Ticket:         |
        Add Hours to Ticket:  0      |
                  Internal?:  0      |
-------------------------------------+-------------------------------------
 This is a ticket created after #2198 has been merged.

 Even with a mutex lock to mutually synchronize threads, we still see the
 mixed log messages. The latest observation is that writes between
 lock...unlock seem to be buffered and happen later after the unlocks
 happen.

 This especially seems to be triggered by the lettuce tests, where BIND 10
 output is redirected using `subprocess.Popen`.

 This is a ticket to investigate this behaviour, and fix the problem.

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