[prev in list] [next in list] [prev in thread] [next in thread]
List: trac-tickets
Subject: [Trac-tickets] [The Trac Project] #1721: tracd crash related to
From: noreply () edgewall ! com (The Trac Project)
Date: 2005-06-27 4:30:11
Message-ID: 043.791b25236c91a2664d9841b712a94138 () edgewall ! com
[Download RAW message or body]
#1721: tracd crash related to pysqlite
---------------------+------------------------------------------------------
Id: 1721 | Status: new
Component: general | Modified: Mon Jun 27 04:30:06 2005
Severity: major | Milestone:
Priority: high | Version: devel
Owner: jonas | Reporter: cboos
---------------------+------------------------------------------------------
After upgrading to [1862] (from [1850] or so), I got a crash
while executing a custom query.
Since this happened very quickly after startup, I include the
full log.
{{{
Trac is brought to you by:
-----------------------------------------------------------------
E d g e w a l l S o f t w a r e
Professional Linux and software development services
Read more at http://www.edgewall.com/
-----------------------------------------------------------------
Running:
D:/Workspace/install/lib/python/trac/devel/Scripts/tracd -p 8002
-a devel,D:/Workspace/local/trac/htdigest.BCT,BCT
D:/Workspace/local/trac/devel
Trac[query] DEBUG: Query SQL: SELECT t.id AS id,t.summary AS
summary,t.status AS status,t.owner AS owner,t.type AS type,t.priority AS
priori
ty,t.milestone AS milestone,t.time AS time,t.changetime AS
changetime,priority.value AS priority_value
FROM ticket AS t
LEFT OUTER JOIN enum AS priority ON (priority.type='priority' AND
priority.name=priority)
WHERE COALESCE(t.status,'') IN ('new','assigned','reopened')
ORDER BY COALESCE(t.priority,'')='',priority.value,t.id
cblaptop - - [27/Jun/2005 23:01:25] "GET /devel/query HTTP/1.1" 200 -
Trac[session] DEBUG: Adding variable query_time with value "1119906085" to
session 865518019c9c2dcea885d966
Trac[session] DEBUG: Adding variable query_constraints with value
"{'status': ('new', 'assigned', 'reopened')}" to session 865518019c9c2dcea
885d966
Trac[session] DEBUG: Adding variable query_href with value
"/devel/query?status=new&status=assigned&status=reopened&order=priority"
to sessi
on 865518019c9c2dcea885d966
Trac[session] DEBUG: Adding variable query_tickets with value "1 2 3 4 5 7
8 9" to session 865518019c9c2dcea885d966
Trac[session] DEBUG: Purging old, expired, sessions.
cblaptop - - [27/Jun/2005 23:01:25] "GET /devel/chrome/css/trac.css
HTTP/1.1" 304 -
Trac[standalone] DEBUG: Total request time: 0.030000 s
cblaptop - - [27/Jun/2005 23:01:25] "GET /devel/chrome/css/report.css
HTTP/1.1" 304 -
Trac[standalone] DEBUG: Total request time: 0.040000 s
cblaptop - - [27/Jun/2005 23:01:25] "GET /devel/chrome/css/code.css
HTTP/1.1" 304 -
Trac[standalone] DEBUG: Total request time: 0.010000 s
cblaptop - - [27/Jun/2005 23:01:25] "GET /devel/chrome/js/trac.js
HTTP/1.1" 200 -
Trac[standalone] DEBUG: Total request time: 0.020000 s
0.0 10.0
Trac[main] ERROR: Failed to render pretty error page: object does not
support item assignment
cblaptop - - [27/Jun/2005 23:01:25] "GET /devel/chrome/topbar_gradient.png
HTTP/1.1" 500 -
0.0699999332428 10.0
Trac[main] ERROR: Failed to render pretty error page: object does not
support item assignment
cblaptop - - [27/Jun/2005 23:01:25] "GET /devel/chrome/dots.gif HTTP/1.1"
500 -
Trac[standalone] DEBUG: Total request time: 0.550000 s
cblaptop - - [27/Jun/2005 23:01:25] "GET
/devel/chrome/topbar_gradient2.png HTTP/1.1" 200 -
Trac[standalone] DEBUG: Total request time: 0.030000 s
cblaptop - - [27/Jun/2005 23:01:25] "GET /devel/chrome/js/query.js
HTTP/1.1" 200 -
Trac[standalone] DEBUG: Total request time: 0.010000 s
cblaptop - - [27/Jun/2005 23:01:25] "GET /devel/chrome/asc.png HTTP/1.1"
200 -
cblaptop - - [27/Jun/2005 23:01:25] "GET /devel/chrome/trac_banner.png
HTTP/1.1" 200 -
Trac[standalone] DEBUG: Total request time: 0.020000 s
Trac[standalone] DEBUG: Total request time: 0.331000 s
cblaptop - - [27/Jun/2005 23:01:25] "GET /devel/chrome/xml.png HTTP/1.1"
200 -
Trac[standalone] DEBUG: Total request time: 0.040000 s
cblaptop - - [27/Jun/2005 23:01:25] "GET /devel/chrome/trac_logo_mini.png
HTTP/1.1" 200 -
Trac[standalone] DEBUG: Total request time: 0.030000 s
cblaptop - - [27/Jun/2005 23:01:34] "POST /devel/query HTTP/1.1" 302 -
Trac[standalone] DEBUG: Total request time: 0.040000 s
Trac[query] DEBUG: Query SQL: SELECT t.id AS id,t.summary AS
summary,t.reporter AS reporter,t.status AS status,t.owner AS owner,t.type
AS ty
pe,t.priority AS priority,t.time AS time,t.changetime AS
changetime,priority.value AS priority_value
FROM ticket AS t
LEFT OUTER JOIN enum AS priority ON (priority.type='priority' AND
priority.name=priority)
WHERE COALESCE(t.status,'') IN ('new','assigned','reopened') AND
COALESCE(t.reporter,'') LIKE '%body%'
ORDER BY COALESCE(t.priority,'')='',priority.value,t.id
cblaptop - - [27/Jun/2005 23:01:34] "GET
/devel/query?status=new&status=assigned&status=reopened&group=&reporter=%7Ebody&order=priority
HTTP
/1.1" 200 -
Trac[session] DEBUG: Changing variable query_time from "1119906085" to
"1119906094" in session 865518019c9c2dcea885d966
Trac[session] DEBUG: Changing variable query_constraints from "{'status':
('new', 'assigned', 'reopened')}" to "{'status': ['new', 'assigned
', 'reopened'], 'reporter': ['~body']}" in session
865518019c9c2dcea885d966
Trac[session] DEBUG: Changing variable query_href from
"/devel/query?status=new&status=assigned&status=reopened&order=priority"
to "/devel/q
uery?status=new&status=assigned&status=reopened&group=&reporter=%7Ebody&order=priority"
in session 865518019c9c2dcea885d966
Trac[session] DEBUG: Changing variable query_tickets from "1 2 3 4 5 7 8
9" to "" in session 865518019c9c2dcea885d966
Trac[session] DEBUG: Purging old, expired, sessions.
Trac[main] ERROR: Failed to render pretty error page: object does not
support item assignment
cblaptop - - [27/Jun/2005 23:01:34] "GET /devel/chrome/topbar_gradient.png
HTTP/1.1" 500 -
Exception _sqlite.ProgrammingError: 'library routine called out of
sequence' in <bound method PooledConnection.__del__ of <trac.db.PooledCon
nection object at 0x00CB5D78>> ignored
cblaptop - - [27/Jun/2005 23:01:34] "GET /devel/chrome/dots.gif HTTP/1.1"
200 -
Trac[standalone] DEBUG: Total request time: 0.020000 s
Fatal Python error: PyEval_RestoreThread: NULL tstate
This application has requested the Runtime to terminate it in an unusual
way.
Please contact the application's support team for more information.
}}}
There are several things to be noted in the above:
* there's an {{{ERROR: Failed to render pretty error page: object does
not support item assignment}}} error. Maybe it's related.
* there is some race condition going on, as demonstrated by the {{{0.0
10.0}}} and {{{0.0699999332428 10.0}}} lines
* the obviously problematic {{{Exception _sqlite.ProgrammingError:
'library routine called out of sequence' in <bound method
PooledConnection.__del__ of <trac.db.PooledConnection object at
0x00CB5D78>> ignored}}}
I initially got a crash with only the {{{Fatal Python error:
PyEval_RestoreThread: NULL tstate}}} information, then I retried
and got the above data. Afterwards, I couldn't reproduce the crash
(well, except by doing fast refreshes, but that's the usual #1401
behavior :) ). Maybe it was because those first two times old expired
sessions were purged concurrently to the ticket query, and after that,
there are no more sessions to purge?
== Configuration ==
* tracd on Windows
* Python 2.3.2
* SVN bindings 1.2.0
* PySqlite 1.1.6
* User agent: Firefox 1.0.4, Windows
--
Ticket URL: <http://projects.edgewall.com/trac/ticket/1721>
The Trac Project <>
[prev in list] [next in list] [prev in thread] [next in thread]
Configure |
About |
News |
Add a list |
Sponsored by KoreLogic