[Buildbot] #3125: flaky state string in buildbot.test.integration.test_customservices.CustomServiceMaster.test_customService

Buildbot trac trac at buildbot.net
Mon Dec 29 00:02:51 UTC 2014


#3125: flaky state string in
buildbot.test.integration.test_customservices.CustomServiceMaster.test_customService
-------------------+--------------------
Reporter:  dustin  |      Owner:  dustin
    Type:  defect  |     Status:  new
Priority:  minor   |  Milestone:  0.9.0
 Version:  0.8.10  |   Keywords:
-------------------+--------------------
 After hundreds of iterations of the integration tests:

 {{{
 [FAIL]
 Traceback (most recent call last):
   File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1099, in _inlineCallbacks
     result = g.send(result)
   File
 "/home/ubuntu/buildbot/master/buildbot/test/integration/test_customservices.py",
 line 32, in test_customService
     self.assertEqual(build['steps'][0]['state_string'], 'num reconfig: 1')
   File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_synctest.py", line 356, in assertEqual
     % (msg, pformat(first), pformat(second)))
 twisted.trial.unittest.FailTest: not equal:
 a = u"'echo hei'"
 b = 'num reconfig: 1'


 buildbot.test.integration.test_customservices.CustomServiceMaster.test_customService
 }}}

 This was on a CPU-loaded EC2 instance with all of the logging from #3081:

 {{{
 2014-12-28 22:30:43+0000 [-] -->
 buildbot.test.integration.test_customservices.CustomServiceMaster.test_customService
 <--
 2014-12-28 22:30:43+0000 [-] Setting up database with URL
 'sqlite:/state.sqlite'
 2014-12-28 22:30:43+0000 [-] setting database journal mode to 'wal'
 2014-12-28 22:30:43+0000 [-] using SQLAlchemy-Migrate version 0.7.2
 2014-12-28 22:30:44+0000 [-] Initializing empty database
 2014-12-28 22:30:48+0000 [-] Starting BuildMaster -- buildbot.version:
 0.9.0
 2014-12-28 22:30:48+0000 [-] Loading configuration from
 '/home/ubuntu/buildbot/_trial_temp/basdir/master.cfg'
 2014-12-28 22:30:48+0000 [-] adding 1 new builders, removing 0
 2014-12-28 22:30:49+0000 [-] trying to load status pickle from
 /home/ubuntu/buildbot/_trial_temp/basdir/testy/builder
 2014-12-28 22:30:49+0000 [-] no saved status pickle, creating a new one
 2014-12-28 22:30:49+0000 [-] added builder testy with tags []
 2014-12-28 22:30:50+0000 [-] adding scheduler 'force'
 2014-12-28 22:30:50+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>.startService
 2014-12-28 22:30:50+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>._startActivityPolling
 2014-12-28 22:30:50+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>._activityPoll
 2014-12-28 22:30:50+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>._activityPoll: _getServiceId
 2014-12-28 22:30:50+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>.startService return
 2014-12-28 22:30:50+0000 [-] No web server configured on this master
 2014-12-28 22:30:50+0000 [-] adding 1 new slaves, removing 0
 2014-12-28 22:30:50+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>._activityPoll: _claimService
 2014-12-28 22:30:50+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>._activityPoll: _stopActivityPolling
 2014-12-28 22:30:50+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>._stopActivityPolling; self._activityPollCall is
 LoopingCall<300>(ForceScheduler._activityPoll, *(), **{})
 2014-12-28 22:30:50+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>._stopActivityPolling; self._activityPollCall.call is None
 2014-12-28 22:30:50+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>._activityPoll: self.active = True
 2014-12-28 22:30:50+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>._activityPoll: self.activate
 2014-12-28 22:30:50+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>._activityPoll: self.activate returned
 2014-12-28 22:30:50+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>._activityPollCall.start returned
 2014-12-28 22:30:51+0000 [-] <pbmanager.Dispatcher for  on
 tcp:0:interface=127.0.0.1>.startService
 2014-12-28 22:30:51+0000 [-]   File
 "/home/ubuntu/buildbot/sandbox/bin/trial", line 18, in <module>
             run()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/scripts/trial.py", line 615, in run
             test_result = trialRunner.run(suite)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 729, in run
             return self._runWithoutDecoration(test,
 self._forceGarbageCollection)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 756, in _runWithoutDecoration
             run()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 751, in <lambda>
             run = lambda: suite.run(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 219, in run
             TestSuite.run(self, result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asyncrunner.py", line 36, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 178, in run
             super(LoggedSuite, self).run(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asyncrunner.py", line 36, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 151, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 151, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 151, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 151, in run
             test(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asynctest.py", line 119, in __call__
             return self.run(*args, **kwargs)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_synctest.py", line 943, in run
             _collectWarnings(self._warnings.append,
 self._runFixturesAndTest, result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_synctest.py", line 182, in _collectWarnings
             result = f(*args, **kwargs)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asynctest.py", line 300, in _runFixturesAndTest
             self._wait(d)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asynctest.py", line 378, in _wait
             reactor.run()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/base.py", line 1192, in run
             self.mainLoop()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/base.py", line 1201, in mainLoop
             self.runUntilCurrent()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/base.py", line 797, in runUntilCurrent
             f(*a, **kw)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 382, in callback
             self._startRunCallbacks(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 490, in _startRunCallbacks
             self._runCallbacks()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 577, in _runCallbacks
             current.result = callback(current.result, *args, **kw)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1155, in gotResult
             _inlineCallbacks(r, g, deferred)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1142, in _inlineCallbacks
             deferred.callback(e.value)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 382, in callback
             self._startRunCallbacks(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 490, in _startRunCallbacks
             self._runCallbacks()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 577, in _runCallbacks
             current.result = callback(current.result, *args, **kw)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1155, in gotResult
             _inlineCallbacks(r, g, deferred)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1142, in _inlineCallbacks
             deferred.callback(e.value)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 382, in callback
             self._startRunCallbacks(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 490, in _startRunCallbacks
             self._runCallbacks()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 577, in _runCallbacks
             current.result = callback(current.result, *args, **kw)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1155, in gotResult
             _inlineCallbacks(r, g, deferred)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1142, in _inlineCallbacks
             deferred.callback(e.value)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 382, in callback
             self._startRunCallbacks(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 490, in _startRunCallbacks
             self._runCallbacks()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 577, in _runCallbacks
             current.result = callback(current.result, *args, **kw)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1155, in gotResult
             _inlineCallbacks(r, g, deferred)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1102, in _inlineCallbacks
             deferred.callback(None)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 382, in callback
             self._startRunCallbacks(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 490, in _startRunCallbacks
             self._runCallbacks()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 577, in _runCallbacks
             current.result = callback(current.result, *args, **kw)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1155, in gotResult
             _inlineCallbacks(r, g, deferred)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1102, in _inlineCallbacks
             deferred.callback(None)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 382, in callback
             self._startRunCallbacks(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 490, in _startRunCallbacks
             self._runCallbacks()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 577, in _runCallbacks
             current.result = callback(current.result, *args, **kw)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1155, in gotResult
             _inlineCallbacks(r, g, deferred)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1102, in _inlineCallbacks
             deferred.callback(None)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 382, in callback
             self._startRunCallbacks(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 490, in _startRunCallbacks
             self._runCallbacks()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 577, in _runCallbacks
             current.result = callback(current.result, *args, **kw)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1155, in gotResult
             _inlineCallbacks(r, g, deferred)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1099, in _inlineCallbacks
             result = g.send(result)
           File
 "/home/ubuntu/buildbot/master/buildbot/buildslave/manager.py", line 94, in
 reconfigServiceWithBuildbotConfig
             new_config)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1237, in unwindGenerator
             return _inlineCallbacks(None, gen, Deferred())
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1099, in _inlineCallbacks
             result = g.send(result)
           File "/home/ubuntu/buildbot/master/buildbot/util/service.py",
 line 43, in reconfigServiceWithBuildbotConfig
             yield svc.reconfigServiceWithBuildbotConfig(new_config)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1237, in unwindGenerator
             return _inlineCallbacks(None, gen, Deferred())
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1099, in _inlineCallbacks
             result = g.send(result)
           File "/home/ubuntu/buildbot/master/buildbot/buildslave/base.py",
 line 225, in reconfigServiceWithBuildbotConfig
             yield self.registration.update(new, new_config)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1237, in unwindGenerator
             return _inlineCallbacks(None, gen, Deferred())
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1099, in _inlineCallbacks
             result = g.send(result)
           File
 "/home/ubuntu/buildbot/master/buildbot/buildslave/manager.py", line 53, in
 update
             global_config.protocols['pb']['port'])
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1237, in unwindGenerator
             return _inlineCallbacks(None, gen, Deferred())
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1099, in _inlineCallbacks
             result = g.send(result)
           File
 "/home/ubuntu/buildbot/master/buildbot/buildslave/protocols/pb.py", line
 49, in updateRegistration
             portStr, username, password, self._getPerspective)
           File "/home/ubuntu/buildbot/master/buildbot/pbmanager.py", line
 59, in register
             disp.setServiceParent(self)
           File "/home/ubuntu/buildbot/master/buildbot/util/service.py",
 line 220, in setServiceParent
             return self.parent.addService(self)
           File "/home/ubuntu/buildbot/master/buildbot/util/service.py",
 line 254, in addService
             return service.startService()
           File "/home/ubuntu/buildbot/master/buildbot/pbmanager.py", line
 140, in startService
             self.port = strports.listen(self.portstr, self.serverFactory)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/application/strports.py", line 99, in listen
             return getattr(reactor, 'listen'+name)(*args, **kw)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/posixbase.py", line 495, in listenTCP
             p.startListening()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/tcp.py", line 993, in startListening
 log.msg(''.join(traceback.format_list(traceback.extract_stack())))

 2014-12-28 22:30:51+0000 [-] PBServerFactory starting on 45694
 2014-12-28 22:30:51+0000 [-] Starting factory
 <twisted.spread.pb.PBServerFactory instance at 0x7fa458e73830>
 2014-12-28 22:30:52+0000 [-] BuildMaster is running
 2014-12-28 22:30:52+0000 [-] Starting BuildSlave -- version: 0.9.0
 2014-12-28 22:30:52+0000 [-] recording hostname in twistd.hostname
 2014-12-28 22:30:52+0000 [-] Starting factory <buildslave.bot.BotFactory
 instance at 0x7fa458e45098>
 2014-12-28 22:30:52+0000 [-] Connecting to 127.0.0.1:45694
 2014-12-28 22:30:52+0000 [Broker,0,127.0.0.1] slave 'local1' attaching
 from IPv4Address(TCP, '127.0.0.1', 49025)
 2014-12-28 22:30:52+0000 [Broker,client] message from master: attached
 2014-12-28 22:30:52+0000 [Broker,0,127.0.0.1] Got slaveinfo from 'local1'
 2014-12-28 22:30:52+0000 [-] bot attached
 2014-12-28 22:30:52+0000 [Broker,0,127.0.0.1] Buildslave local1 attached
 to testy
 2014-12-28 22:30:52+0000 [Broker,client] message from master: attached
 2014-12-28 22:30:52+0000 [Broker,client] Connected to 127.0.0.1:45694;
 slave is ready
 2014-12-28 22:30:53+0000 [-] added buildset 1 to database
 2014-12-28 22:30:54+0000 [-] starting build <Build testy> using slave
 <SlaveBuilder builder=u'testy' slave=u'local1'>
 2014-12-28 22:30:54+0000 [-] acquireLocks(slave <BuildSlave u'local1'>,
 locks [])
 2014-12-28 22:30:54+0000 [-] starting build <Build testy>.. pinging the
 slave <SlaveBuilder builder=u'testy' slave=u'local1'>
 2014-12-28 22:30:54+0000 [-] sending ping
 2014-12-28 22:30:54+0000 [Broker,client] message from master: ping
 2014-12-28 22:30:54+0000 [Broker,0,127.0.0.1] ping finished: success
 2014-12-28 22:30:54+0000 [Broker,0,127.0.0.1] <Build testy>.startBuild
 2014-12-28 22:30:56+0000 [-]
 ShellCommand.startCommand(cmd=<RemoteShellCommand ''echo hei''>)
 2014-12-28 22:30:56+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}
 2014-12-28 22:30:56+0000 [-] <RemoteShellCommand ''echo hei''>:
 RemoteCommand.run [0]
 2014-12-28 22:30:56+0000 [-] command 'echo hei' in dir 'build'
 2014-12-28 22:30:56+0000 [Broker,client]  startCommand:shell [id 0]
 2014-12-28 22:30:56+0000 [Broker,client] RunProcess._startCommand
 2014-12-28 22:30:56+0000 [Broker,client]  echo hei
 2014-12-28 22:30:56+0000 [Broker,client]   in dir
 /home/ubuntu/buildbot/_trial_temp/basdir/testy/build (timeout 1200 secs)
 2014-12-28 22:30:56+0000 [Broker,client]   watching logfiles {}
 2014-12-28 22:30:56+0000 [Broker,client]   argv: echo hei
 2014-12-28 22:30:56+0000 [Broker,client]  environment: {'LESSOPEN': '|
 /usr/bin/lesspipe %s', 'SSH_CLIENT': '71.164.95.83 55589 22', 'LOGNAME':
 'ubuntu', 'USER': 'ubuntu', 'PATH':
 '/home/ubuntu/buildbot/sandbox/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games',
 'HOME': '/home/ubuntu', 'PS1':
 '(sandbox)${debian_chroot:+($debian_chroot)}\\u@\\h:\\w\\$ ', 'LANG':
 'en_US.UTF-8', 'TERM': 'screen', 'SHELL': '/bin/bash', 'SHLVL': '1',
 'XDG_RUNTIME_DIR': '/run/user/1000', 'SSH_AUTH_SOCK': '/tmp/ssh-
 eG6TFPrNnc/agent.1328', 'VIRTUAL_ENV': '/home/ubuntu/buildbot/sandbox',
 'XDG_SESSION_ID': '1', '_': '/home/ubuntu/buildbot/sandbox/bin/trial',
 'LS_COLORS':
 'rs=0:di=01;34:ln=01;36:mh=00:pi=40;33:so=01;35:do=01;35:bd=40;33;01:cd=40;33;01:or=40;31;01:su=37;41:sg=30;43:ca=30;41:tw=30;42:ow=34;42:st=37;44:ex=01;32:*.tar=01;31:*.tgz=01;31:*.arj=01;31:*.taz=01;31:*.lzh=01;31:*.lzma=01;31:*.tlz=01;31:*.txz=01;31:*.zip=01;31:*.z=01;31:*.Z=01;31:*.dz=01;31:*.gz=01;31:*.lz=01;31:*.xz=01;31:*.bz2=01;31:*.bz=01;31:*.tbz=01;31:*.tbz2=01;31:*.tz=01;31:*.deb=01;31:*.rpm=01;31:*.jar=01;31:*.war=01;31:*.ear=01;31:*.sar=01;31:*.rar=01;31:*.ace=01;31:*.zoo=01;31:*.cpio=01;31:*.7z=01;31:*.rz=01;31:*.jpg=01;35:*.jpeg=01;35:*.gif=01;35:*.bmp=01;35:*.pbm=01;35:*.pgm=01;35:*.ppm=01;35:*.tga=01;35:*.xbm=01;35:*.xpm=01;35:*.tif=01;35:*.tiff=01;35:*.png=01;35:*.svg=01;35:*.svgz=01;35:*.mng=01;35:*.pcx=01;35:*.mov=01;35:*.mpg=01;35:*.mpeg=01;35:*.m2v=01;35:*.mkv=01;35:*.webm=01;35:*.ogm=01;35:*.mp4=01;35:*.m4v=01;35:*.mp4v=01;35:*.vob=01;35:*.qt=01;35:*.nuv=01;35:*.wmv=01;35:*.asf=01;35:*.rm=01;35:*.rmvb=01;35:*.flc=01;35:*.avi=01;35:*.fli=01;35:*.flv=01;35:*.gl=01;35:*.dl=01;35:*.xcf=01;35:*.xwd=01;35:*.yuv=01;35:*.cgm=01;35:*.emf=01;35:*.axv=01;35:*.anx=01;35:*.ogv=01;35:*.ogx=01;35:*.aac=00;36:*.au=00;36:*.flac=00;36:*.mid=00;36:*.midi=00;36:*.mka=00;36:*.mp3=00;36:*.mpc=00;36:*.ogg=00;36:*.ra=00;36:*.wav=00;36:*.axa=00;36:*.oga=00;36:*.spx=00;36:*.xspf=00;36:',
 'LESSCLOSE': '/usr/bin/lesspipe %s %s', 'SSH_TTY': '/dev/pts/0', 'OLDPWD':
 '/home/ubuntu', 'PWD':
 '/home/ubuntu/buildbot/_trial_temp/basdir/testy/build', 'MAIL':
 '/var/mail/ubuntu', 'SSH_CONNECTION': '71.164.95.83 55589 172.18.1.183
 22'}
 2014-12-28 22:30:56+0000 [Broker,client]   using PTY: False
 2014-12-28 22:30:56+0000 [-] command finished with signal None, exit code
 0, elapsedTime: 0.034538
 2014-12-28 22:30:56+0000 [-] SlaveBuilder.commandComplete
 <buildslave.commands.shell.SlaveShellCommand instance at 0x7fa458e84bd8>
 2014-12-28 22:30:56+0000 [Broker,0,127.0.0.1] <RemoteShellCommand ''echo
 hei''> rc=0
 2014-12-28 22:30:56+0000 [-] closing log
 <buildbot.process.buildstep.SyncLogFileWrapper object at 0x7fa458547810>
 2014-12-28 22:30:58+0000 [-]
 releaseLocks(<buildbot.test.integration.test_customservices.MyShellCommand
 object at 0x7fa45854cf10>): []
 2014-12-28 22:31:04+0000 [-]  step 'shell' complete: success
 2014-12-28 22:31:04+0000 [-]  <Build testy>: build finished
 2014-12-28 22:31:05+0000 [-] releaseLocks(<BuildSlave u'local1'>): []
 2014-12-28 22:31:05+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>.stopService
 2014-12-28 22:31:05+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>.stopService _stopActivityPolling
 2014-12-28 22:31:05+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>._stopActivityPolling; self._activityPollCall is None
 2014-12-28 22:31:05+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>.stopService d = <Deferred at 0x7fa459bb4b90 current
 result: LoopingCall<300>(ForceScheduler._activityPoll, *(), **{})>
 2014-12-28 22:31:05+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>.stopService deactivate_if_needed; active=True
 2014-12-28 22:31:05+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>.stopService deactivate_if_needed; set active=False
 2014-12-28 22:31:05+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>.stopService deactivate_if_needed; call self.deactivate
 2014-12-28 22:31:05+0000 [-] PBManager._unregister <pbmanager.Registration
 for local1 on tcp:0:interface=127.0.0.1>
 2014-12-28 22:31:05+0000 [-] PBManager._unregister <pbmanager.Registration
 for None on tcp:0:interface=127.0.0.1> - all users gone
 2014-12-28 22:31:05+0000 [-] <pbmanager.Dispatcher for  on
 tcp:0:interface=127.0.0.1>.stopService (self.running=1)
 2014-12-28 22:31:05+0000 [-]   File
 "/home/ubuntu/buildbot/sandbox/bin/trial", line 18, in <module>
             run()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/scripts/trial.py", line 615, in run
             test_result = trialRunner.run(suite)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 729, in run
             return self._runWithoutDecoration(test,
 self._forceGarbageCollection)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 756, in _runWithoutDecoration
             run()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 751, in <lambda>
             run = lambda: suite.run(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 219, in run
             TestSuite.run(self, result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asyncrunner.py", line 36, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 178, in run
             super(LoggedSuite, self).run(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asyncrunner.py", line 36, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 151, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 151, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 151, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 151, in run
             test(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asynctest.py", line 119, in __call__
             return self.run(*args, **kwargs)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_synctest.py", line 943, in run
             _collectWarnings(self._warnings.append,
 self._runFixturesAndTest, result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_synctest.py", line 182, in _collectWarnings
             result = f(*args, **kwargs)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asynctest.py", line 300, in _runFixturesAndTest
             self._wait(d)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asynctest.py", line 378, in _wait
             reactor.run()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/base.py", line 1192, in run
             self.mainLoop()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/base.py", line 1201, in mainLoop
             self.runUntilCurrent()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/base.py", line 824, in runUntilCurrent
             call.func(*call.args, **call.kw)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 382, in callback
             self._startRunCallbacks(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 490, in _startRunCallbacks
             self._runCallbacks()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 577, in _runCallbacks
             current.result = callback(current.result, *args, **kw)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/task.py", line 832, in <lambda>
             d.addCallback(lambda ignored: callable(*args, **kw))
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/tcp.py", line 1114, in connectionLost
             self._logConnectionLostMsg()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/tcp.py", line 1106, in _logConnectionLostMsg
 log.msg(''.join(traceback.format_list(traceback.extract_stack())))

 2014-12-28 22:31:05+0000 [-] (TCP Port 45694 Closed)
 2014-12-28 22:31:05+0000 [-] Stopping factory
 <twisted.spread.pb.PBServerFactory instance at 0x7fa458e73830>
 2014-12-28 22:31:05+0000 [-] <pbmanager.Dispatcher for  on
 tcp:0:interface=127.0.0.1>.stopService - stopListening returned None
 2014-12-28 22:31:05+0000 [-] disp.disownServiceParent returns None
 2014-12-28 22:31:05+0000 [Broker,client] Lost connection to
 127.0.0.1:45694
 2014-12-28 22:31:05+0000 [Broker,client] Stopping factory
 <buildslave.bot.BotFactory instance at 0x7fa458e45098>
 2014-12-28 22:31:05+0000 [Broker,0,127.0.0.1] BuildSlave.detached(local1)
 2014-12-28 22:31:05+0000 [Broker,0,127.0.0.1] releaseLocks(<BuildSlave
 u'local1'>): []
 2014-12-28 22:31:05+0000 [Broker,0,127.0.0.1] Buildslave local1 detached
 from testy
 2014-12-28 22:31:06+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>.stopService deactivate_if_needed; self.deactivate done
 with True
 2014-12-28 22:31:06+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa458e9d390>.stopService return
 2014-12-28 22:31:06+0000 [-] BuildMaster is stopped
 2014-12-28 22:31:06+0000 [-] Main loop terminated.
 2014-12-28 22:31:06+0000 [-] -->
 buildbot.test.integration.test_master.RunMaster.test_master1 <--
 2014-12-28 22:31:06+0000 [-] Loading configuration from
 '/home/ubuntu/buildbot/_trial_temp/basdir/master.cfg'
 2014-12-28 22:31:06+0000 [-] Setting up database with URL
 'sqlite:/state.sqlite'
 2014-12-28 22:31:06+0000 [-] setting database journal mode to 'wal'
 2014-12-28 22:31:07+0000 [-] using SQLAlchemy-Migrate version 0.7.2
 2014-12-28 22:31:08+0000 [-] Initializing empty database
 2014-12-28 22:31:21+0000 [-] Starting BuildMaster -- buildbot.version:
 0.9.0
 2014-12-28 22:31:21+0000 [-] Loading configuration from
 '/home/ubuntu/buildbot/_trial_temp/basdir/master.cfg'
 2014-12-28 22:31:22+0000 [-] adding 1 new changesources, removing 0
 2014-12-28 22:31:22+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>.startService
 2014-12-28 22:31:22+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>._startActivityPolling
 2014-12-28 22:31:22+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>._activityPoll
 2014-12-28 22:31:22+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>._activityPoll: _getServiceId
 2014-12-28 22:31:22+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>.startService return
 2014-12-28 22:31:22+0000 [-] adding 1 new builders, removing 0
 2014-12-28 22:31:22+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>._activityPoll: _claimService
 2014-12-28 22:31:22+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>._activityPoll: _stopActivityPolling
 2014-12-28 22:31:22+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>._stopActivityPolling; self._activityPollCall is
 LoopingCall<300>(PBChangeSource._activityPoll, *(), **{})
 2014-12-28 22:31:22+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>._stopActivityPolling; self._activityPollCall.call is None
 2014-12-28 22:31:22+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>._activityPoll: self.active = True
 2014-12-28 22:31:22+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>._activityPoll: self.activate
 2014-12-28 22:31:22+0000 [-] <pbmanager.Dispatcher for  on
 tcp:0>.startService
 2014-12-28 22:31:22+0000 [-]   File
 "/home/ubuntu/buildbot/sandbox/bin/trial", line 18, in <module>
             run()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/scripts/trial.py", line 615, in run
             test_result = trialRunner.run(suite)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 729, in run
             return self._runWithoutDecoration(test,
 self._forceGarbageCollection)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 756, in _runWithoutDecoration
             run()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 751, in <lambda>
             run = lambda: suite.run(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 219, in run
             TestSuite.run(self, result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asyncrunner.py", line 36, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 178, in run
             super(LoggedSuite, self).run(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asyncrunner.py", line 36, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 151, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 151, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 151, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 151, in run
             test(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asynctest.py", line 119, in __call__
             return self.run(*args, **kwargs)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_synctest.py", line 943, in run
             _collectWarnings(self._warnings.append,
 self._runFixturesAndTest, result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_synctest.py", line 182, in _collectWarnings
             result = f(*args, **kwargs)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asynctest.py", line 300, in _runFixturesAndTest
             self._wait(d)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asynctest.py", line 378, in _wait
             reactor.run()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/base.py", line 1192, in run
             self.mainLoop()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/base.py", line 1201, in mainLoop
             self.runUntilCurrent()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/base.py", line 797, in runUntilCurrent
             f(*a, **kw)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 382, in callback
             self._startRunCallbacks(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 490, in _startRunCallbacks
             self._runCallbacks()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 577, in _runCallbacks
             current.result = callback(current.result, *args, **kw)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1155, in gotResult
             _inlineCallbacks(r, g, deferred)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 1099, in _inlineCallbacks
             result = g.send(result)
           File "/home/ubuntu/buildbot/master/buildbot/util/service.py",
 line 202, in _activityPoll
             yield self.activate()
           File "/home/ubuntu/buildbot/master/buildbot/changes/pb.py", line
 152, in activate
             self._register(port)
           File "/home/ubuntu/buildbot/master/buildbot/changes/pb.py", line
 164, in _register
             self.getPerspective)
           File "/home/ubuntu/buildbot/master/buildbot/pbmanager.py", line
 59, in register
             disp.setServiceParent(self)
           File "/home/ubuntu/buildbot/master/buildbot/util/service.py",
 line 220, in setServiceParent
             return self.parent.addService(self)
           File "/home/ubuntu/buildbot/master/buildbot/util/service.py",
 line 254, in addService
             return service.startService()
           File "/home/ubuntu/buildbot/master/buildbot/pbmanager.py", line
 140, in startService
             self.port = strports.listen(self.portstr, self.serverFactory)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/application/strports.py", line 99, in listen
             return getattr(reactor, 'listen'+name)(*args, **kw)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/posixbase.py", line 495, in listenTCP
             p.startListening()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/tcp.py", line 993, in startListening
 log.msg(''.join(traceback.format_list(traceback.extract_stack())))

 2014-12-28 22:31:22+0000 [-] PBServerFactory starting on 35205
 2014-12-28 22:31:22+0000 [-] Starting factory
 <twisted.spread.pb.PBServerFactory instance at 0x7fa45854b638>
 2014-12-28 22:31:22+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>._activityPoll: self.activate returned
 2014-12-28 22:31:22+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>._activityPollCall.start returned
 2014-12-28 22:31:23+0000 [-] trying to load status pickle from
 /home/ubuntu/buildbot/_trial_temp/basdir/testy/builder
 2014-12-28 22:31:23+0000 [-] no saved status pickle, creating a new one
 2014-12-28 22:31:23+0000 [-] added builder testy with tags []
 2014-12-28 22:31:23+0000 [-] adding scheduler 'all'
 2014-12-28 22:31:24+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>.startService
 2014-12-28 22:31:24+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>._startActivityPolling
 2014-12-28 22:31:24+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>._activityPoll
 2014-12-28 22:31:24+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>._activityPoll: _getServiceId
 2014-12-28 22:31:24+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>.startService return
 2014-12-28 22:31:24+0000 [-] adding scheduler 'force'
 2014-12-28 22:31:24+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>._activityPoll: _claimService
 2014-12-28 22:31:24+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>._activityPoll: _stopActivityPolling
 2014-12-28 22:31:24+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>._stopActivityPolling; self._activityPollCall is
 LoopingCall<300>(AnyBranchScheduler._activityPoll, *(), **{})
 2014-12-28 22:31:24+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>._stopActivityPolling;
 self._activityPollCall.call is None
 2014-12-28 22:31:24+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>._activityPoll: self.active = True
 2014-12-28 22:31:24+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>._activityPoll: self.activate
 2014-12-28 22:31:24+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>._activityPoll: self.activate returned
 2014-12-28 22:31:24+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>._activityPollCall.start returned
 2014-12-28 22:31:25+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>.startService
 2014-12-28 22:31:25+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>._startActivityPolling
 2014-12-28 22:31:25+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>._activityPoll
 2014-12-28 22:31:25+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>._activityPoll: _getServiceId
 2014-12-28 22:31:25+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>.startService return
 2014-12-28 22:31:25+0000 [-] No web server configured on this master
 2014-12-28 22:31:25+0000 [-] adding 1 new slaves, removing 0
 2014-12-28 22:31:25+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>._activityPoll: _claimService
 2014-12-28 22:31:25+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>._activityPoll: _stopActivityPolling
 2014-12-28 22:31:25+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>._stopActivityPolling; self._activityPollCall is
 LoopingCall<300>(ForceScheduler._activityPoll, *(), **{})
 2014-12-28 22:31:25+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>._stopActivityPolling; self._activityPollCall.call is None
 2014-12-28 22:31:25+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>._activityPoll: self.active = True
 2014-12-28 22:31:25+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>._activityPoll: self.activate
 2014-12-28 22:31:25+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>._activityPoll: self.activate returned
 2014-12-28 22:31:25+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>._activityPollCall.start returned
 2014-12-28 22:31:26+0000 [-] BuildMaster is running
 2014-12-28 22:31:26+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>.stopService
 2014-12-28 22:31:26+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>.stopService _stopActivityPolling
 2014-12-28 22:31:26+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>._stopActivityPolling; self._activityPollCall is None
 2014-12-28 22:31:26+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>.stopService d = <Deferred at 0x7fa4585767a0 current
 result: LoopingCall<300>(ForceScheduler._activityPoll, *(), **{})>
 2014-12-28 22:31:26+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>.stopService deactivate_if_needed; active=True
 2014-12-28 22:31:26+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>.stopService deactivate_if_needed; set active=False
 2014-12-28 22:31:26+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>.stopService deactivate_if_needed; call self.deactivate
 2014-12-28 22:31:26+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>.stopService
 2014-12-28 22:31:26+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>.stopService _stopActivityPolling
 2014-12-28 22:31:26+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>._stopActivityPolling; self._activityPollCall is
 None
 2014-12-28 22:31:26+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>.stopService d = <Deferred at 0x7fa4585b0c20
 current result: LoopingCall<300>(AnyBranchScheduler._activityPoll, *(),
 **{})>
 2014-12-28 22:31:26+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>.stopService deactivate_if_needed; active=True
 2014-12-28 22:31:26+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>.stopService deactivate_if_needed; set
 active=False
 2014-12-28 22:31:26+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>.stopService deactivate_if_needed; call
 self.deactivate
 2014-12-28 22:31:26+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>.stopService
 2014-12-28 22:31:26+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>.stopService _stopActivityPolling
 2014-12-28 22:31:26+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>._stopActivityPolling; self._activityPollCall is None
 2014-12-28 22:31:26+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>.stopService d = <Deferred at 0x7fa459b955a8 current
 result: LoopingCall<300>(PBChangeSource._activityPoll, *(), **{})>
 2014-12-28 22:31:26+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>.stopService deactivate_if_needed; active=True
 2014-12-28 22:31:26+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>.stopService deactivate_if_needed; set active=False
 2014-12-28 22:31:26+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>.stopService deactivate_if_needed; call self.deactivate
 2014-12-28 22:31:26+0000 [-] PBManager._unregister <pbmanager.Registration
 for change on tcp:0>
 2014-12-28 22:31:26+0000 [-] PBManager._unregister <pbmanager.Registration
 for local1 on tcp:0>
 2014-12-28 22:31:26+0000 [-] PBManager._unregister <pbmanager.Registration
 for None on tcp:0> - all users gone
 2014-12-28 22:31:26+0000 [-] <pbmanager.Dispatcher for  on
 tcp:0>.stopService (self.running=1)
 2014-12-28 22:31:26+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>.stopService deactivate_if_needed; self.deactivate done
 with True
 2014-12-28 22:31:26+0000 [-]
 <buildbot.schedulers.forcesched.ForceScheduler object at
 0x7fa45a62b810>.stopService return
 2014-12-28 22:31:26+0000 [-]   File
 "/home/ubuntu/buildbot/sandbox/bin/trial", line 18, in <module>
             run()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/scripts/trial.py", line 615, in run
             test_result = trialRunner.run(suite)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 729, in run
             return self._runWithoutDecoration(test,
 self._forceGarbageCollection)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 756, in _runWithoutDecoration
             run()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 751, in <lambda>
             run = lambda: suite.run(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 219, in run
             TestSuite.run(self, result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asyncrunner.py", line 36, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 178, in run
             super(LoggedSuite, self).run(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asyncrunner.py", line 36, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 151, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 151, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 151, in run
             test(result)
           File "/usr/lib/python2.7/unittest/suite.py", line 70, in
 __call__
             return self.run(*args, **kwds)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/runner.py", line 151, in run
             test(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asynctest.py", line 119, in __call__
             return self.run(*args, **kwargs)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_synctest.py", line 943, in run
             _collectWarnings(self._warnings.append,
 self._runFixturesAndTest, result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_synctest.py", line 182, in _collectWarnings
             result = f(*args, **kwargs)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asynctest.py", line 300, in _runFixturesAndTest
             self._wait(d)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/trial/_asynctest.py", line 378, in _wait
             reactor.run()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/base.py", line 1192, in run
             self.mainLoop()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/base.py", line 1201, in mainLoop
             self.runUntilCurrent()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/base.py", line 824, in runUntilCurrent
             call.func(*call.args, **call.kw)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 382, in callback
             self._startRunCallbacks(result)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 490, in _startRunCallbacks
             self._runCallbacks()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/defer.py", line 577, in _runCallbacks
             current.result = callback(current.result, *args, **kw)
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/task.py", line 832, in <lambda>
             d.addCallback(lambda ignored: callable(*args, **kw))
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/tcp.py", line 1114, in connectionLost
             self._logConnectionLostMsg()
           File "/home/ubuntu/buildbot/sandbox/local/lib/python2.7/site-
 packages/twisted/internet/tcp.py", line 1106, in _logConnectionLostMsg
 log.msg(''.join(traceback.format_list(traceback.extract_stack())))

 2014-12-28 22:31:26+0000 [-] (TCP Port 35205 Closed)
 2014-12-28 22:31:26+0000 [-] Stopping factory
 <twisted.spread.pb.PBServerFactory instance at 0x7fa45854b638>
 2014-12-28 22:31:26+0000 [-] <pbmanager.Dispatcher for  on
 tcp:0>.stopService - stopListening returned None
 2014-12-28 22:31:26+0000 [-] disp.disownServiceParent returns None
 2014-12-28 22:31:26+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>.stopService deactivate_if_needed;
 self.deactivate done with True
 2014-12-28 22:31:26+0000 [-] <buildbot.schedulers.basic.AnyBranchScheduler
 object at 0x7fa45a62b710>.stopService return
 2014-12-28 22:31:27+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>.stopService deactivate_if_needed; self.deactivate done
 with True
 2014-12-28 22:31:27+0000 [-] <buildbot.changes.pb.PBChangeSource object at
 0x7fa45a62b610>.stopService return
 2014-12-28 22:31:28+0000 [-] BuildMaster is stopped
 2014-12-28 22:31:28+0000 [-] Main loop terminated.
 }}}

--
Ticket URL: <http://trac.buildbot.net/ticket/3125>
Buildbot <http://buildbot.net/>
Buildbot: build/test automation


More information about the bugs mailing list