Ticket #3543: log

File log, 10.7 KB (added by stefan, 3 years ago)

error log

Line 
1Following twistd.log until startup finished..
22016-04-29 11:09:10-0400 [-] Log opened.
32016-04-29 11:09:10-0400 [-] twistd 16.1.1 (/usr/local/Continuum/anaconda/envs/bb/bin/python 2.7.11) starting up.
42016-04-29 11:09:10-0400 [-] reactor class: twisted.internet.epollreactor.EPollReactor.
52016-04-29 11:09:10-0400 [-] Starting BuildMaster -- buildbot.version: 0.8.13-pre-83-gcc31072
62016-04-29 11:09:10-0400 [-] Loading configuration from '/home/stefan/projects/buildbot/master/sandbox/master/master.cfg'
72016-04-29 11:09:11-0400 [-] Setting up database with URL 'sqlite:///state.sqlite'
82016-04-29 11:09:11-0400 [-] setting database journal mode to 'wal'
92016-04-29 11:09:11-0400 [-] adding 1 new changesources, removing 0
102016-04-29 11:09:11-0400 [-] gitpoller: using workdir '/home/stefan/projects/buildbot/master/sandbox/master/gitpoller-workdir'
112016-04-29 11:09:11-0400 [-] adding 1 new slaves, removing 0
122016-04-29 11:09:11-0400 [-] adding 1 new builders, removing 0
132016-04-29 11:09:11-0400 [-] trying to load status pickle from /home/stefan/projects/buildbot/master/sandbox/master/runtests/builder
142016-04-29 11:09:11-0400 [-] added builder runtests with tags None
152016-04-29 11:09:11-0400 [-] PBServerFactory starting on 9989
162016-04-29 11:09:11-0400 [-] Starting factory <twisted.spread.pb.PBServerFactory instance at 0x7f5dcf8602d8>
172016-04-29 11:09:11-0400 [-] adding scheduler 'all'
182016-04-29 11:09:11-0400 [-] adding scheduler 'force'
192016-04-29 11:09:11-0400 [-] prioritizing builders; order unspecified
20        Traceback (most recent call last):
21        Failure: twisted.internet.defer.FirstError: FirstError[#0, [Failure instance: Traceback: <class 'sqlalchemy.exc.OperationalError'>: (OperationalError) no such column: buildrequests.buildername u'SELECT buildrequests.id, buildrequests.buildsetid, buildrequests.buildername, buildrequests.priority, buildrequests.complete, buildrequests.results, buildrequests.submitted_at, buildrequests.complete_at, buildrequest_claims.brid, buildrequest_claims.objectid, buildrequest_claims.claimed_at \nFROM buildrequests LEFT OUTER JOIN buildrequest_claims ON buildrequests.id = buildrequest_claims.brid \nWHERE buildrequest_claims.claimed_at IS NULL AND buildrequests.complete = ? AND buildrequests.buildername = ?' (0, 'runtests')
22        /usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/internet/defer.py:434:errback
23        /usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/internet/defer.py:501:_startRunCallbacks
24        /usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/internet/defer.py:588:_runCallbacks
25        /usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/internet/defer.py:1184:gotResult
26        --- <exception caught here> ---
27        /usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/internet/defer.py:1126:_inlineCallbacks
28        /usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/python/failure.py:389:throwExceptionIntoGenerator
29        /usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/buildbot-0.8.13_pre_83_gcc31072-py2.7.egg/buildbot/process/builder.py:136:getOldestRequestTime
30        /usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/python/threadpool.py:246:inContext
31        /usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/python/threadpool.py:262:<lambda>
32        /usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/python/context.py:118:callWithContext
33        /usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/python/context.py:81:callWithContext
34        /usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/buildbot-0.8.13_pre_83_gcc31072-py2.7.egg/buildbot/db/pool.py:191:__thd
35        /usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/buildbot-0.8.13_pre_83_gcc31072-py2.7.egg/buildbot/db/buildrequests.py:120:thd
36        build/bdist.linux-x86_64/egg/sqlalchemy/engine/base.py:1449:execute
37        build/bdist.linux-x86_64/egg/sqlalchemy/engine/base.py:1584:_execute_clauseelement
38        build/bdist.linux-x86_64/egg/sqlalchemy/engine/base.py:1698:_execute_context
39        build/bdist.linux-x86_64/egg/sqlalchemy/engine/base.py:1691:_execute_context
40        build/bdist.linux-x86_64/egg/sqlalchemy/engine/default.py:331:do_execute
41        ]]
42       
432016-04-29 11:09:11-0400 [-] Unhandled error in Deferred:
442016-04-29 11:09:11-0400 [-] Unhandled Error
45        Traceback (most recent call last):
46          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/internet/defer.py", line 434, in errback
47            self._startRunCallbacks(fail)
48          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/internet/defer.py", line 501, in _startRunCallbacks
49            self._runCallbacks()
50          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/internet/defer.py", line 588, in _runCallbacks
51            current.result = callback(current.result, *args, **kw)
52          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/internet/defer.py", line 1184, in gotResult
53            _inlineCallbacks(r, g, deferred)
54        --- <exception caught here> ---
55          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/internet/defer.py", line 1126, in _inlineCallbacks
56            result = result.throwExceptionIntoGenerator(g)
57          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/python/failure.py", line 389, in throwExceptionIntoGenerator
58            return g.throw(self.type, self.value, self.tb)
59          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/buildbot-0.8.13_pre_83_gcc31072-py2.7.egg/buildbot/process/builder.py", line 136, in getOldestRequestTime
60            buildername=self.name, claimed=False)
61          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/python/threadpool.py", line 246, in inContext
62            result = inContext.theWork()
63          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/python/threadpool.py", line 262, in <lambda>
64            inContext.theWork = lambda: context.call(ctx, func, *args, **kw)
65          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/python/context.py", line 118, in callWithContext
66            return self.currentContext().callWithContext(ctx, func, *args, **kw)
67          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/python/context.py", line 81, in callWithContext
68            return func(*args,**kw)
69          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/buildbot-0.8.13_pre_83_gcc31072-py2.7.egg/buildbot/db/pool.py", line 191, in __thd
70            rv = callable(arg, *args, **kwargs)
71          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/buildbot-0.8.13_pre_83_gcc31072-py2.7.egg/buildbot/db/buildrequests.py", line 120, in thd
72            res = conn.execute(q)
73          File "build/bdist.linux-x86_64/egg/sqlalchemy/engine/base.py", line 1449, in execute
74           
75          File "build/bdist.linux-x86_64/egg/sqlalchemy/engine/base.py", line 1584, in _execute_clauseelement
76           
77          File "build/bdist.linux-x86_64/egg/sqlalchemy/engine/base.py", line 1698, in _execute_context
78           
79          File "build/bdist.linux-x86_64/egg/sqlalchemy/engine/base.py", line 1691, in _execute_context
80           
81          File "build/bdist.linux-x86_64/egg/sqlalchemy/engine/default.py", line 331, in do_execute
82           
83        sqlalchemy.exc.OperationalError: (OperationalError) no such column: buildrequests.buildername u'SELECT buildrequests.id, buildrequests.buildsetid, buildrequests.buildername, buildrequests.priority, buildrequests.complete, buildrequests.results, buildrequests.submitted_at, buildrequests.complete_at, buildrequest_claims.brid, buildrequest_claims.objectid, buildrequest_claims.claimed_at \nFROM buildrequests LEFT OUTER JOIN buildrequest_claims ON buildrequests.id = buildrequest_claims.brid \nWHERE buildrequest_claims.claimed_at IS NULL AND buildrequests.complete = ? AND buildrequests.buildername = ?' (0, 'runtests')
84       
852016-04-29 11:09:11-0400 [-] while starting SingleBranchScheduler 'all'
86        Traceback (most recent call last):
87          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/threading.py", line 801, in __bootstrap_inner
88            self.run()
89          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/threading.py", line 754, in run
90            self.__target(*self.__args, **self.__kwargs)
91          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/_threads/_threadworker.py", line 46, in work
92            task()
93          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/_threads/_team.py", line 190, in doWork
94            task()
95        --- <exception caught here> ---
96          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/python/threadpool.py", line 246, in inContext
97            result = inContext.theWork()
98          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/python/threadpool.py", line 262, in <lambda>
99            inContext.theWork = lambda: context.call(ctx, func, *args, **kw)
100          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/python/context.py", line 118, in callWithContext
101            return self.currentContext().callWithContext(ctx, func, *args, **kw)
102          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/twisted/python/context.py", line 81, in callWithContext
103            return func(*args,**kw)
104          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/buildbot-0.8.13_pre_83_gcc31072-py2.7.egg/buildbot/db/pool.py", line 191, in __thd
105            rv = callable(arg, *args, **kwargs)
106          File "/usr/local/Continuum/anaconda/envs/bb/lib/python2.7/site-packages/buildbot-0.8.13_pre_83_gcc31072-py2.7.egg/buildbot/db/schedulers.py", line 61, in thd
107            conn.execute(q)
108          File "build/bdist.linux-x86_64/egg/sqlalchemy/engine/base.py", line 1449, in execute
109           
110          File "build/bdist.linux-x86_64/egg/sqlalchemy/engine/base.py", line 1584, in _execute_clauseelement
111           
112          File "build/bdist.linux-x86_64/egg/sqlalchemy/engine/base.py", line 1698, in _execute_context
113           
114          File "build/bdist.linux-x86_64/egg/sqlalchemy/engine/base.py", line 1691, in _execute_context
115           
116          File "build/bdist.linux-x86_64/egg/sqlalchemy/engine/default.py", line 331, in do_execute
117           
118        sqlalchemy.exc.OperationalError: (OperationalError) no such column: scheduler_changes.objectid u'DELETE FROM scheduler_changes WHERE scheduler_changes.objectid = ?' (2,)
119       
1202016-04-29 11:09:11-0400 [-] WebStatus using (/home/stefan/projects/buildbot/master/sandbox/master/public_html)
1212016-04-29 11:09:11-0400 [-] RotateLogSite starting on 8010
1222016-04-29 11:09:11-0400 [-] Starting factory <buildbot.status.web.baseweb.RotateLogSite instance at 0x7f5dcf004998>
1232016-04-29 11:09:11-0400 [-] Setting up http.log rotating 10 files of 10000000 bytes each
1242016-04-29 11:09:11-0400 [-] BuildMaster is running
125The buildmaster appears to have (re)started correctly.