Log from buildbot master running my test

twistd.log

$ git clone git@github.com:devoid/bb-latent-slave-bug.git
$ cd bb-latent-slave-bug.git
$ git checkout 4dd952448bdad897260e5cd26e1314775b534096
$ ./run.sh
...
$ cat ./logs/bb-master-twistd.log 
2015-08-07 05:08:29+0000 [-] Log opened.
2015-08-07 05:08:29+0000 [-] twistd 15.3.0 (/usr/bin/python 2.7.6) starting up.
2015-08-07 05:08:29+0000 [-] reactor class: twisted.internet.epollreactor.EPollReactor.
2015-08-07 05:08:29+0000 [-] Starting BuildMaster -- buildbot.version: 0.8.12
2015-08-07 05:08:29+0000 [-] Loading configuration from '/data/buildbot/master.cfg'
2015-08-07 05:08:29+0000 [-] Setting up database with URL 'sqlite:////data/buildbot/db/state.sqlite'
2015-08-07 05:08:29+0000 [-] setting database journal mode to 'wal'
2015-08-07 05:08:29+0000 [-] adding 1 new changesources, removing 0
2015-08-07 05:08:29+0000 [-] PBServerFactory starting on 9999
2015-08-07 05:08:29+0000 [-] Starting factory <twisted.spread.pb.PBServerFactory instance at 0x7f42d9105200>
2015-08-07 05:08:29+0000 [-] adding 1 new slaves, removing 0
2015-08-07 05:08:29+0000 [-] adding 1 new builders, removing 0
2015-08-07 05:08:29+0000 [-] trying to load status pickle from /data/buildbot/builder/builder
2015-08-07 05:08:29+0000 [-] no saved status pickle, creating a new one
2015-08-07 05:08:29+0000 [-] added builder builder with tags None
2015-08-07 05:08:29+0000 [-] PBServerFactory starting on 9989
2015-08-07 05:08:29+0000 [-] Starting factory <twisted.spread.pb.PBServerFactory instance at 0x7f42d91059e0>
2015-08-07 05:08:29+0000 [-] Latent buildslave builder-01 attached to builder
2015-08-07 05:08:29+0000 [-] adding scheduler 'tester'
2015-08-07 05:08:29+0000 [-] WebStatus using (/data/buildbot/public_html)
2015-08-07 05:08:29+0000 [-] RotateLogSite starting on 8010
2015-08-07 05:08:29+0000 [-] Starting factory <buildbot.status.web.baseweb.RotateLogSite instance at 0x7f42d88d3e18>
2015-08-07 05:08:29+0000 [-] Setting up http.log rotating 10 files of 10000000 bytes each
2015-08-07 05:08:29+0000 [-] BuildMaster is running
2015-08-07 05:08:34+0000 [Broker,0,127.0.0.1] perspective_addChange called
2015-08-07 05:08:34+0000 [Broker,0,127.0.0.1] No files listed in change... bit strange, but not fatal.
2015-08-07 05:08:34+0000 [-] added change Change(revision=None, who=u'devoid', branch=u'master', comments=u'comment-1', when=1438924114, category=None, project=u'', repository=u'', codebase=u'') to database
2015-08-07 05:08:34+0000 [-] added buildset 1 to database
2015-08-07 05:08:34+0000 [-] starting build <Build builder> using slave <LatentSlaveBuilder builder='builder' slave='builder-01'>
2015-08-07 05:08:34+0000 [-] acquireLocks(slave <DockerLatentBuildSlave 'builder-01'>, locks [])
2015-08-07 05:08:34+0000 [-] substantiating slave <LatentSlaveBuilder builder='builder' slave='builder-01'>
2015-08-07 05:08:34+0000 [-] Container created, Id: 1b4c38...
2015-08-07 05:08:34+0000 [-] /usr/local/lib/python2.7/dist-packages/docker/client.py:795: exceptions.DeprecationWarning: Passing host config parameters in start() is deprecated. Please use host_config in create_container instead!
2015-08-07 05:08:34+0000 [-] Container started
2015-08-07 05:08:35+0000 [Broker,0,172.17.0.44] slave 'builder-01' attaching from IPv4Address(TCP, '172.17.0.44', 39433)
2015-08-07 05:08:35+0000 [Broker,0,172.17.0.44] Got slaveinfo from 'builder-01'
2015-08-07 05:08:35+0000 [Broker,0,172.17.0.44] Starting buildslave keepalive timer for 'builder-01'
2015-08-07 05:08:35+0000 [Broker,0,172.17.0.44] bot attached
2015-08-07 05:08:35+0000 [Broker,0,172.17.0.44] Buildslave builder-01 attached to builder
2015-08-07 05:08:35+0000 [Broker,0,172.17.0.44] Slave builder-01 substantiated \o/
2015-08-07 05:08:35+0000 [Broker,0,172.17.0.44] Firing builder-01 substantiation deferred with success
2015-08-07 05:08:35+0000 [Broker,0,172.17.0.44] starting build <Build builder>.. pinging the slave <LatentSlaveBuilder builder='builder' slave='builder-01'>
2015-08-07 05:08:35+0000 [Broker,0,172.17.0.44] sending ping
2015-08-07 05:08:35+0000 [Broker,0,172.17.0.44] ping finished: success
2015-08-07 05:08:35+0000 [-] <Build builder>.startBuild
2015-08-07 05:08:35+0000 [-] ShellCommand.startCommand(cmd=<RemoteShellCommand '['sleep', '10']'>)
2015-08-07 05:08:35+0000 [-]   cmd.args = {'workdir': 'build', 'env': None, 'want_stderr': 1, 'logEnviron': True, 'sigtermTime': None, 'logfiles': {}, 'timeout': 1200, 'want_stdout': 1, 'usePTY': 'slave-config', 'maxTime': None, 'initial_stdin': None}
2015-08-07 05:08:35+0000 [-] <RemoteShellCommand '['sleep', '10']'>: RemoteCommand.run [0]
2015-08-07 05:08:35+0000 [-] command '['sleep', '10']' in dir 'build'
2015-08-07 05:08:35+0000 [Broker,1,127.0.0.1] perspective_addChange called
2015-08-07 05:08:35+0000 [Broker,1,127.0.0.1] No files listed in change... bit strange, but not fatal.
2015-08-07 05:08:35+0000 [-] added change Change(revision=None, who=u'devoid', branch=u'master', comments=u'comment-2', when=1438924115, category=None, project=u'', repository=u'', codebase=u'') to database
2015-08-07 05:08:35+0000 [-] added buildset 2 to database
2015-08-07 05:08:36+0000 [Broker,2,127.0.0.1] perspective_addChange called
2015-08-07 05:08:36+0000 [Broker,2,127.0.0.1] No files listed in change... bit strange, but not fatal.
2015-08-07 05:08:36+0000 [-] added change Change(revision=None, who=u'devoid', branch=u'master', comments=u'comment-3', when=1438924116, category=None, project=u'', repository=u'', codebase=u'') to database
2015-08-07 05:08:36+0000 [-] added buildset 3 to database
2015-08-07 05:08:45+0000 [Broker,0,172.17.0.44] <RemoteShellCommand '['sleep', '10']'> rc=0
2015-08-07 05:08:45+0000 [-] closing log <buildbot.status.logfile.LogFile instance at 0x7f42d804c200>
2015-08-07 05:08:45+0000 [-] releaseLocks(<buildbot.steps.shell.ShellCommand object at 0x7f42d8081a10>): []
2015-08-07 05:08:45+0000 [-]  step 'shell' complete: success
2015-08-07 05:08:45+0000 [-]  <Build builder>: build finished
2015-08-07 05:08:45+0000 [-]  setting expectations for next time
2015-08-07 05:08:45+0000 [-] new expectations: 10.0134100914 seconds
2015-08-07 05:08:45+0000 [-] Stopping container 1b4c38...
2015-08-07 05:08:45+0000 [-] releaseLocks(<DockerLatentBuildSlave 'builder-01'>): []
2015-08-13 16:44:31+0000 [Broker,0,172.17.0.44] BuildSlave.detached(builder-01)
2015-08-13 16:44:31+0000 [Broker,0,172.17.0.44] releaseLocks(<DockerLatentBuildSlave 'builder-01'>): []