Traceback (most recent call last):
  File "/home/vsts/work/1/s/tests/log_handler_test.py", line 87, in test_earliest_messages_logged_via
    self.assertIn('Python version is', logs)
AssertionError: 'Python version is' not found in u'starting function call to c1: mitogen.parent._proxy_connect(kwargs=Kwargs({u\'profiling\': False, \'debug\': True, u\'unidirectional\': False}), method_name=u\'local\', name=u\'c2\')\nsending message to Context(1, u\'c1\'): Message(1, 0, 0, 101, 1001, \'\\x80\\x02(NX\\x0e\\x00\\x00\\x00mitogen.parentNX\\x0e\\x00\\x00\\x00_proxy_connect)cmitog\'..194)\nDispatcher: dispatching (None, u\'mitogen.parent\', None, u\'_proxy_connect\', (), Kwargs({\'kwargs\': Kwargs({u\'profiling\': False, \'debug\': True, u\'unidirectional\': False}), \'method_name\': u\'local\', \'name\': u\'c2\'}))\nupgraded Poller with EpollPoller (new: 4 readers, 0 writers; old: 4 readers, 0 writers)\nupgrading Router(Broker(7e50)) with capabilities to start new children\nsending message to Context(0, u\'master\'): Message(0, 1, 1, 105, 1000, \'\'..0)\nIdAllocator(Router(Broker(1e10))): allocating [2..1002)\nIdAllocator(Router(Broker(1e10))): allocating [2..1002) to Context(1, u\'c1\')\ncreating connection to context 2 using mitogen.parent\ncommand line for Connection(None): /home/vsts/work/1/s/.tox/py27-mode_mitogen-distro_debian10/bin/python -c "import sys;sys.path=[p for p in sys.path if p];import binascii,os,zlib;exec(zlib.decompress(binascii.a2b_base64(\\"eNqVkl1PwjAUhu/5Fd6dLo7RDuVjcYkE8CORjywoMbiQjXXaCO3SDib8ejswsOGF8a5P3/f0vG2PZ05doayEJRQZFWlmBWLxhYZYyE9kOJV8Ha0TG2GTYGyc2DOLJLVKDrxYCkWRVwRZhGkRMg2HhmserHT7GfZdF3qBzBiHgEdFyfZnju3fAGnvFbVVFv2ii3UahEuqq2prJWsh47Vkm34IDs6Z49xgW034d3u9b2ObHBNsqFRM8JlTzyvzIx1dQ/mGSb0L49fJw2g4f+o8D7sP/d78zhsN5lOvMx73PfBdILB/jaO/492/YC2Uox88GpeoLJhlvAS0Yql4p9zZqFTdxptqsGu2SLXZqDtX1626AUZFH5VJllJETBg8TkYY4zcOOsJCRPqeRqXr7pYstCK6EKtEUqVQPhCRSCjXYwAyBMOSNIgQabZJQxfEiXtyTE3IQsinI05+GnX368OPn7mzv9y/05Jy2uMs2cY3c/XlZg==\\")))"\nchild for Connection(None) started: pid:4587 stdin:15 stdout:15 stderr:None\nBootstrapProtocol(c2): first stage started succcessfully\nBootstrapProtocol(c2): first stage received mitogen.core source\nBootstrapProtocol(c2): new child booted successfully\nRouter(Broker(7e50)): registering Context(2, u\'c2\') to stream <Stream c2 #4f90>\nAdding route to 2 via <Stream c1 #9b50>\nRouter(Broker(1e10)): adding route to context 2 via <Stream c1 #9b50>\nDispatcher: Message(1, 0, 0, 101, 1001, \'\\x80\\x02(NX\\x0e\\x00\\x00\\x00mitogen.parentNX\\x0e\\x00\\x00\\x00_proxy_connect)cmitog\'..194) -> {u\'id\': 2, u\'msg\': None, u\'name\': u\'c2\'}\nContext(2, u\'c1.c2\').shutdown() sending SHUTDOWN\nshutdown request from context 0\nBroker(ce50): shutting down\nIoLoggerProtocol(stdout): shutting down\nIoLoggerProtocol(stderr): shutting down\nWaker(fd=3/4): shutting down\nWaker(fd=3/4): disconnecting\nMitogenProtocol(parent): shutting down\n<Side of stdout fd 8>: empty read, disconnecting\nIoLoggerProtocol(stdout): disconnecting\n<Side of stderr fd 10>: empty read, disconnecting\nIoLoggerProtocol(stderr): disconnecting\nBroker(ce50): force disconnecting <Side of parent fd 5>\nparent stream is gone, dying.\nBroker(ce50): shutting down\n<Side of c2 fd 15>: empty read, disconnecting\nPopenProcess c2 pid 4587 still running after IO disconnect, recheck in 0.050s\nContext(2, u\'c2\'): disconnecting\nstream c2 is gone; propagating DEL_ROUTE for set([2])\nRouter(Broker(7e50)): deleting route to 2\nfiring local disconnect signal for Context(2, u\'c1.c2\')\ndeleting route to 2 via <Stream c1 #9b50>\nRouter(Broker(1e10)): deleting route to 2\nMitogenProtocol(c2): disconnecting\n'