2012-10-24 16:01:18,687 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:18,701 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:18,701 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:18,703 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:18,703 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:18,703 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:18,704 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:18,704 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:18,704 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:18,706 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:18,706 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:18,706 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.component.getAll' with args: () 2012-10-24 16:01:18,707 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:18,709 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:18,710 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:18,710 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.component.getAll 2012-10-24 16:01:18,711 Trac[util] DEBUG: SQL: '\n SELECT name,owner,description FROM component ORDER BY name\n ' 2012-10-24 16:01:18,713 Trac[util] DEBUG: prefetch: 3 rows 2012-10-24 16:01:18,713 Trac[xml_rpc] DEBUG: RPC(xml) 'ticket.component.getAll' result: [u'Issue Tracker', u'LOID 7', u'LOID 8'] 2012-10-24 16:01:18,776 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:18,778 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:18,779 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:18,779 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:18,779 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:18,780 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:18,780 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:18,780 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:18,780 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:18,782 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:18,782 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:18,782 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.multicall' with args: ([{'methodName': 'ticket.component.get', 'params': ['Issue Tracker']}, {'methodName': 'ticket.component.get', 'params': ['LOID 7']}, {'methodName': 'ticket.component.get', 'params': ['LOID 8']}],) 2012-10-24 16:01:18,782 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:18,783 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.multicall 2012-10-24 16:01:18,785 Trac[util] DEBUG: SQL: '\n SELECT owner,description FROM component WHERE name=%s\n ' 2012-10-24 16:01:18,785 Trac[util] DEBUG: args: ('Issue Tracker',) 2012-10-24 16:01:18,786 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:18,788 Trac[util] DEBUG: SQL: '\n SELECT owner,description FROM component WHERE name=%s\n ' 2012-10-24 16:01:18,788 Trac[util] DEBUG: args: ('LOID 7',) 2012-10-24 16:01:18,789 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:18,790 Trac[util] DEBUG: SQL: '\n SELECT owner,description FROM component WHERE name=%s\n ' 2012-10-24 16:01:18,790 Trac[util] DEBUG: args: ('LOID 8',) 2012-10-24 16:01:18,792 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:18,792 Trac[xml_rpc] DEBUG: RPC(xml) 'system.multicall' result: [({'owner': '', 'name': 'Issue Tracker', 'description': ''},), ({'owner': '', 'name': 'LOID 7', 'description': ''},), ({'owner': '', 'name': 'LOID 8', 'description': ''},)] 2012-10-24 16:01:18,857 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:18,858 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:18,858 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:18,858 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:18,859 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:18,861 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:18,861 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:18,861 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:18,861 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:18,861 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:18,861 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:18,861 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.milestone.getAll' with args: () 2012-10-24 16:01:18,862 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:18,862 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.milestone.getAll 2012-10-24 16:01:18,865 Trac[util] DEBUG: SQL: 'SELECT name,due,completed,description FROM milestone ' 2012-10-24 16:01:18,865 Trac[util] DEBUG: prefetch: 6 rows 2012-10-24 16:01:18,867 Trac[xml_rpc] DEBUG: RPC(xml) 'ticket.milestone.getAll' result: [u'LOID 7.0', u'Crossings 1.0', u'LOID 7.1', u'LOID 7.2', u'Crossings Future Release', u'Future Roadmap'] 2012-10-24 16:01:18,930 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:18,931 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:18,931 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:18,931 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:18,933 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:18,934 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:18,934 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:18,934 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:18,934 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:18,934 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:18,934 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:18,936 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.multicall' with args: ([{'methodName': 'ticket.milestone.get', 'params': ['LOID 7.0']}, {'methodName': 'ticket.milestone.get', 'params': ['Crossings 1.0']}, {'methodName': 'ticket.milestone.get', 'params': ['LOID 7.1']}, {'methodName': 'ticket.milestone.get', 'params': ['LOID 7.2']}, {'methodName': 'ticket.milestone.get', 'params': ['Crossings Future Release']}, {'methodName': 'ticket.milestone.get', 'params': ['Future Roadmap']}],) 2012-10-24 16:01:18,937 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:18,937 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.multicall 2012-10-24 16:01:18,938 Trac[util] DEBUG: SQL: '\n SELECT name,due,completed,description \n FROM milestone WHERE name=%s\n ' 2012-10-24 16:01:18,938 Trac[util] DEBUG: args: ('LOID 7.0',) 2012-10-24 16:01:18,940 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:18,941 Trac[util] DEBUG: SQL: '\n SELECT name,due,completed,description \n FROM milestone WHERE name=%s\n ' 2012-10-24 16:01:18,941 Trac[util] DEBUG: args: ('Crossings 1.0',) 2012-10-24 16:01:18,943 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:18,944 Trac[util] DEBUG: SQL: '\n SELECT name,due,completed,description \n FROM milestone WHERE name=%s\n ' 2012-10-24 16:01:18,944 Trac[util] DEBUG: args: ('LOID 7.1',) 2012-10-24 16:01:18,946 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:18,947 Trac[util] DEBUG: SQL: '\n SELECT name,due,completed,description \n FROM milestone WHERE name=%s\n ' 2012-10-24 16:01:18,947 Trac[util] DEBUG: args: ('LOID 7.2',) 2012-10-24 16:01:18,948 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:18,950 Trac[util] DEBUG: SQL: '\n SELECT name,due,completed,description \n FROM milestone WHERE name=%s\n ' 2012-10-24 16:01:18,950 Trac[util] DEBUG: args: ('Crossings Future Release',) 2012-10-24 16:01:18,951 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:18,953 Trac[util] DEBUG: SQL: '\n SELECT name,due,completed,description \n FROM milestone WHERE name=%s\n ' 2012-10-24 16:01:18,953 Trac[util] DEBUG: args: ('Future Roadmap',) 2012-10-24 16:01:18,954 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:18,954 Trac[xml_rpc] DEBUG: RPC(xml) 'system.multicall' result: [({'due': datetime.datetime(2012, 7, 14, 22, 0, tzinfo=), 'completed': datetime.datetime(2012, 7, 21, 22, 0, tzinfo=), 'description': u'The goal is to fix all critical bugs and deliver to Enbridge a stable working release of LOID.', 'name': u'LOID 7.0'},), ({'due': datetime.datetime(2012, 8, 17, 22, 0, tzinfo=), 'completed': 0, 'description': u'Initial crossings release.', 'name': u'Crossings 1.0'},), ({'due': datetime.datetime(2012, 11, 15, 23, 0, tzinfo=), 'completed': 0, 'description': u'The goal here is to redesign and implement a new database for LOID while creating the least amount of impact on the front-end application. We will do the minimum to restore existing functionality to the front-end.', 'name': u'LOID 7.1'},), ({'due': datetime.datetime(2012, 12, 1, 23, 0, tzinfo=), 'completed': 0, 'description': u'The goal of this phase is to begin leveraging the back-end changes that have occurred in the LOID 7.0/7.1 to implement better data validation in the front-end.', 'name': u'LOID 7.2'},), ({'due': 0, 'completed': 0, 'description': u'The next planned release of Crossings will be to address improvements and changes to the initial release.', 'name': u'Crossings Future Release'},), ({'due': 0, 'completed': 0, 'description': u"This milestone is used to declare that there are tentative plans to address tickets, but there aren't any concrete plans at this point to do so.", 'name': u'Future Roadmap'},)] 2012-10-24 16:01:19,017 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:19,019 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:19,019 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:19,020 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:19,020 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,022 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,022 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:19,022 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,023 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:19,023 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:19,023 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:19,023 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.priority.getAll' with args: () 2012-10-24 16:01:19,023 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:19,023 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.priority.getAll 2012-10-24 16:01:19,026 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM enum WHERE type=%s \n ORDER BY\n CAST(value AS integer)' 2012-10-24 16:01:19,026 Trac[util] DEBUG: args: ('priority',) 2012-10-24 16:01:19,028 Trac[util] DEBUG: prefetch: 4 rows 2012-10-24 16:01:19,028 Trac[xml_rpc] DEBUG: RPC(xml) 'ticket.priority.getAll' result: [u'urgent', u'high', u'medium', u'low'] 2012-10-24 16:01:19,088 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:19,091 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:19,091 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:19,091 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:19,092 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,092 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,092 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:19,092 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,094 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:19,094 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:19,094 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:19,095 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.multicall' with args: ([{'methodName': 'ticket.priority.get', 'params': ['urgent']}, {'methodName': 'ticket.priority.get', 'params': ['high']}, {'methodName': 'ticket.priority.get', 'params': ['medium']}, {'methodName': 'ticket.priority.get', 'params': ['low']}],) 2012-10-24 16:01:19,095 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:19,095 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.multicall 2012-10-24 16:01:19,098 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:19,098 Trac[util] DEBUG: args: ('priority', 'urgent') 2012-10-24 16:01:19,098 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,101 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:19,101 Trac[util] DEBUG: args: ('priority', 'high') 2012-10-24 16:01:19,102 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,105 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:19,105 Trac[util] DEBUG: args: ('priority', 'medium') 2012-10-24 16:01:19,105 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,108 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:19,108 Trac[util] DEBUG: args: ('priority', 'low') 2012-10-24 16:01:19,108 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,109 Trac[xml_rpc] DEBUG: RPC(xml) 'system.multicall' result: [(u'1',), (u'2',), (u'3',), (u'4',)] 2012-10-24 16:01:19,173 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:19,174 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:19,174 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:19,174 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:19,174 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,176 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,176 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:19,177 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,177 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:19,177 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:19,177 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:19,177 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.resolution.getAll' with args: () 2012-10-24 16:01:19,177 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:19,178 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.resolution.getAll 2012-10-24 16:01:19,180 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM enum WHERE type=%s \n ORDER BY\n CAST(value AS integer)' 2012-10-24 16:01:19,180 Trac[util] DEBUG: args: ('resolution',) 2012-10-24 16:01:19,181 Trac[util] DEBUG: prefetch: 7 rows 2012-10-24 16:01:19,181 Trac[xml_rpc] DEBUG: RPC(xml) 'ticket.resolution.getAll' result: [u'none', u'fixed', u'unable to reproduce', u'duplicate', u'works as intended', u'budget declined', u'out of scope'] 2012-10-24 16:01:19,242 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:19,243 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:19,243 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:19,244 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:19,244 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,246 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,246 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:19,246 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,246 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:19,247 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:19,247 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:19,249 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.multicall' with args: ([{'methodName': 'ticket.resolution.get', 'params': ['none']}, {'methodName': 'ticket.resolution.get', 'params': ['fixed']}, {'methodName': 'ticket.resolution.get', 'params': ['unable to reproduce']}, {'methodName': 'ticket.resolution.get', 'params': ['duplicate']}, {'methodName': 'ticket.resolution.get', 'params': ['works as intended']}, {'methodName': 'ticket.resolution.get', 'params': ['budget declined']}, {'methodName': 'ticket.resolution.get', 'params': ['out of scope']}],) 2012-10-24 16:01:19,250 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:19,250 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.multicall 2012-10-24 16:01:19,252 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:19,252 Trac[util] DEBUG: args: ('resolution', 'none') 2012-10-24 16:01:19,253 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,255 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:19,255 Trac[util] DEBUG: args: ('resolution', 'fixed') 2012-10-24 16:01:19,256 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,257 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:19,259 Trac[util] DEBUG: args: ('resolution', 'unable to reproduce') 2012-10-24 16:01:19,259 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,262 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:19,262 Trac[util] DEBUG: args: ('resolution', 'duplicate') 2012-10-24 16:01:19,263 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,265 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:19,265 Trac[util] DEBUG: args: ('resolution', 'works as intended') 2012-10-24 16:01:19,266 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,267 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:19,267 Trac[util] DEBUG: args: ('resolution', 'budget declined') 2012-10-24 16:01:19,269 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,270 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:19,270 Trac[util] DEBUG: args: ('resolution', 'out of scope') 2012-10-24 16:01:19,272 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,272 Trac[xml_rpc] DEBUG: RPC(xml) 'system.multicall' result: [(u'1',), (u'2',), (u'3',), (u'4',), (u'5',), (u'6',), (u'7',)] 2012-10-24 16:01:19,336 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:19,338 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:19,338 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:19,339 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:19,339 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,341 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,367 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:19,367 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,367 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:19,368 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:19,368 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:19,368 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.severity.getAll' with args: () 2012-10-24 16:01:19,368 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:19,368 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.severity.getAll 2012-10-24 16:01:19,404 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM enum WHERE type=%s \n ORDER BY\n CAST(value AS integer)' 2012-10-24 16:01:19,405 Trac[util] DEBUG: args: ('severity',) 2012-10-24 16:01:19,407 Trac[util] DEBUG: prefetch: 5 rows 2012-10-24 16:01:19,407 Trac[xml_rpc] DEBUG: RPC(xml) 'ticket.severity.getAll' result: [u'blocker', u'critical', u'important', u'minor', u'trivial'] 2012-10-24 16:01:19,471 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:19,473 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:19,473 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:19,473 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:19,473 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,476 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,476 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:19,476 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,477 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:19,480 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:19,480 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:19,483 Trac[util] DEBUG: SQL: 'SELECT name FROM auth_cookie WHERE cookie=%s' 2012-10-24 16:01:19,483 Trac[util] DEBUG: args: ('2b30711b0dacc340538567533ab6902b',) 2012-10-24 16:01:19,483 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:19,483 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:19,484 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.multicall' with args: ([{'methodName': 'ticket.severity.get', 'params': ['blocker']}, {'methodName': 'ticket.severity.get', 'params': ['critical']}, {'methodName': 'ticket.severity.get', 'params': ['important']}, {'methodName': 'ticket.severity.get', 'params': ['minor']}, {'methodName': 'ticket.severity.get', 'params': ['trivial']}],) 2012-10-24 16:01:19,484 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,486 Trac[session] DEBUG: Retrieving session for ID u'tylerlyn' 2012-10-24 16:01:19,486 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:19,486 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.multicall 2012-10-24 16:01:19,487 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:19,487 Trac[util] DEBUG: args: (u'tylerlyn', 1) 2012-10-24 16:01:19,490 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,490 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:19,490 Trac[util] DEBUG: args: (u'tylerlyn', 1) 2012-10-24 16:01:19,492 Trac[util] DEBUG: prefetch: 6 rows 2012-10-24 16:01:19,493 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:19,493 Trac[util] DEBUG: args: ('severity', 'blocker') 2012-10-24 16:01:19,494 Trac[util] DEBUG: SQL: 'SELECT name FROM auth_cookie WHERE cookie=%s' 2012-10-24 16:01:19,494 Trac[util] DEBUG: args: ('2b30711b0dacc340538567533ab6902b',) 2012-10-24 16:01:19,499 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,500 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,506 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,506 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:19,506 Trac[util] DEBUG: args: ('severity', 'critical') 2012-10-24 16:01:19,510 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,510 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,512 Trac[perm] DEBUG: No policy allowed tylerlyn performing TRAC_ADMIN on None 2012-10-24 16:01:19,513 Trac[util] DEBUG: SQL: 'SELECT id,generation FROM cache' 2012-10-24 16:01:19,515 Trac[util] DEBUG: prefetch: 10 rows 2012-10-24 16:01:19,516 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:19,516 Trac[util] DEBUG: args: ('severity', 'important') 2012-10-24 16:01:19,519 Trac[util] DEBUG: SQL: "SELECT value FROM system WHERE name='initial_database_version'" 2012-10-24 16:01:19,519 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,520 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,526 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:19,526 Trac[util] DEBUG: args: ('severity', 'minor') 2012-10-24 16:01:19,529 Trac[util] DEBUG: SQL: "SELECT DISTINCT s.sid, n.value, e.value FROM session AS s LEFT JOIN session_attribute AS n ON (n.sid=s.sid and n.authenticated=1 AND n.name = 'name') LEFT JOIN session_attribute AS e ON (e.sid=s.sid AND e.authenticated=1 AND e.name = 'email') WHERE s.authenticated=1 ORDER BY s.sid" 2012-10-24 16:01:19,532 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,538 Trac[util] DEBUG: prefetch: 21 rows 2012-10-24 16:01:19,538 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:19,539 Trac[util] DEBUG: args: ('severity', 'trivial') 2012-10-24 16:01:19,539 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,542 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,543 Trac[xml_rpc] DEBUG: RPC(xml) 'system.multicall' result: [(u'1',), (u'2',), (u'3',), (u'4',), (u'5',)] 2012-10-24 16:01:19,545 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,546 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,546 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,548 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,549 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,592 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,595 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,596 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,598 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,599 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,601 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,602 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,605 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:19,605 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,607 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:19,608 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:19,608 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,608 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:19,608 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,611 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,612 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,614 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:19,614 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,614 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,615 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:19,617 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:19,617 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:19,618 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.getAPIVersion' with args: () 2012-10-24 16:01:19,618 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:19,618 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.getAPIVersion 2012-10-24 16:01:19,619 Trac[xml_rpc] DEBUG: RPC(xml) 'system.getAPIVersion' result: [1, 1, 2] 2012-10-24 16:01:19,621 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,621 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,622 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,624 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,625 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,627 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,670 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,671 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,674 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,674 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,677 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,677 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,678 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,681 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,681 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:19,684 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,684 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:19,686 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:19,687 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,687 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:19,687 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,694 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,694 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,694 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:19,696 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,697 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,697 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:19,698 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:19,698 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:19,698 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.status.getAll' with args: () 2012-10-24 16:01:19,700 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:19,700 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.status.getAll 2012-10-24 16:01:19,703 Trac[xml_rpc] DEBUG: RPC(xml) 'ticket.status.getAll' result: [u'Implementation', u'More Information', u'Requires Budget', u'Triage', u'Verification', u'closed', 'new'] 2012-10-24 16:01:19,704 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,706 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,707 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,707 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,710 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,710 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,756 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,759 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,760 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,763 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,766 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,766 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:19,767 Trac[perm] DEBUG: No policy allowed cindyadam performing TRAC_ADMIN on 2012-10-24 16:01:19,769 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:19,772 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:19,773 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:19,773 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,773 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,780 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,780 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:19,782 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,782 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,783 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:19,783 Trac[perm] DEBUG: No policy allowed jeffanderson performing TRAC_ADMIN on 2012-10-24 16:01:19,785 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:19,786 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:19,789 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.multicall' with args: ([{'methodName': 'ticket.status.get', 'params': ['Implementation']}, {'methodName': 'ticket.status.get', 'params': ['More Information']}, {'methodName': 'ticket.status.get', 'params': ['Requires Budget']}, {'methodName': 'ticket.status.get', 'params': ['Triage']}, {'methodName': 'ticket.status.get', 'params': ['Verification']}, {'methodName': 'ticket.status.get', 'params': ['closed']}, {'methodName': 'ticket.status.get', 'params': ['new']}],) 2012-10-24 16:01:19,792 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,792 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:19,792 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.multicall 2012-10-24 16:01:19,811 Trac[xml_rpc] DEBUG: RPC(xml) 'system.multicall' result: [('0',), ('0',), ('0',), ('0',), ('0',), ('0',), ('0',)] 2012-10-24 16:01:19,811 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,812 Trac[perm] DEBUG: No policy allowed enbridge performing TRAC_ADMIN on 2012-10-24 16:01:19,815 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,816 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,818 Trac[perm] DEBUG: No policy allowed danielmunroe performing TRAC_ADMIN on 2012-10-24 16:01:19,862 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,865 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,865 Trac[perm] DEBUG: No policy allowed chantellesauer performing TRAC_ADMIN on 2012-10-24 16:01:19,865 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on 2012-10-24 16:01:19,868 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,868 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,869 Trac[perm] DEBUG: No policy allowed wyattbeaumont performing TRAC_ADMIN on 2012-10-24 16:01:19,871 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,874 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:19,875 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,877 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:19,878 Trac[perm] DEBUG: No policy allowed test_manager performing TRAC_ADMIN on 2012-10-24 16:01:19,878 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:19,881 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:19,881 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,882 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,882 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,882 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:19,884 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,884 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:19,884 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,885 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:19,885 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:19,887 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.type.getAll' with args: () 2012-10-24 16:01:19,888 Trac[perm] DEBUG: No policy allowed test_developer performing TRAC_ADMIN on 2012-10-24 16:01:19,890 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:19,891 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.type.getAll 2012-10-24 16:01:19,895 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,898 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM enum WHERE type=%s \n ORDER BY\n CAST(value AS integer)' 2012-10-24 16:01:19,898 Trac[util] DEBUG: args: ('ticket_type',) 2012-10-24 16:01:19,898 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,900 Trac[perm] DEBUG: No policy allowed rudolfobrist performing TRAC_ADMIN on 2012-10-24 16:01:19,901 Trac[util] DEBUG: prefetch: 4 rows 2012-10-24 16:01:19,903 Trac[xml_rpc] DEBUG: RPC(xml) 'ticket.type.getAll' result: [u'defect', u'change request', u'new feature', u'optimization'] 2012-10-24 16:01:19,904 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,904 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,904 Trac[perm] DEBUG: No policy allowed andrealatos performing TRAC_ADMIN on 2012-10-24 16:01:19,907 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,951 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,953 Trac[perm] DEBUG: No policy allowed tianyufu performing TRAC_ADMIN on 2012-10-24 16:01:19,954 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,957 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,957 Trac[perm] DEBUG: No policy allowed fayejackson performing TRAC_ADMIN on 2012-10-24 16:01:19,959 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,960 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,961 Trac[perm] DEBUG: No policy allowed duncanbell performing TRAC_ADMIN on 2012-10-24 16:01:19,964 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:19,967 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:19,969 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,969 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:19,970 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:19,971 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,971 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,973 Trac[perm] DEBUG: No policy allowed test_tester performing TRAC_ADMIN on 2012-10-24 16:01:19,973 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,974 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:19,974 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:19,976 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:19,976 Trac[util] DEBUG: SQL: 'SELECT username,action FROM permission' 2012-10-24 16:01:19,979 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:19,980 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:19,980 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.multicall' with args: ([{'methodName': 'ticket.type.get', 'params': ['defect']}, {'methodName': 'ticket.type.get', 'params': ['change request']}, {'methodName': 'ticket.type.get', 'params': ['new feature']}, {'methodName': 'ticket.type.get', 'params': ['optimization']}],) 2012-10-24 16:01:19,982 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:19,983 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.multicall 2012-10-24 16:01:19,986 Trac[util] DEBUG: prefetch: 72 rows 2012-10-24 16:01:19,987 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:19,987 Trac[util] DEBUG: args: ('ticket_type', 'defect') 2012-10-24 16:01:19,989 Trac[perm] DEBUG: No policy allowed danielmbewa performing TRAC_ADMIN on 2012-10-24 16:01:19,990 Trac[perm] DEBUG: No policy allowed tylerlyn performing TRAC_ADMIN on 2012-10-24 16:01:19,990 Trac[perm] DEBUG: No policy allowed tylerlyn performing TRAC_ADMIN on 2012-10-24 16:01:19,990 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,993 Trac[util] DEBUG: SQL: '\n SELECT name,due,completed,description \n FROM milestone WHERE name=%s\n ' 2012-10-24 16:01:19,994 Trac[util] DEBUG: args: (u'LOID 7.0',) 2012-10-24 16:01:19,997 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:19,997 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:19,997 Trac[util] DEBUG: args: ('ticket_type', 'change request') 2012-10-24 16:01:20,000 Trac[util] DEBUG: SQL: '\n SELECT name,due,completed,description \n FROM milestone WHERE name=%s\n ' 2012-10-24 16:01:20,002 Trac[util] DEBUG: args: (u'Crossings 1.0',) 2012-10-24 16:01:20,002 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,005 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,006 Trac[util] DEBUG: SQL: '\n SELECT name,due,completed,description \n FROM milestone WHERE name=%s\n ' 2012-10-24 16:01:20,006 Trac[util] DEBUG: args: (u'LOID 7.1',) 2012-10-24 16:01:20,007 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:20,007 Trac[util] DEBUG: args: ('ticket_type', 'new feature') 2012-10-24 16:01:20,009 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,012 Trac[util] DEBUG: SQL: '\n SELECT name,due,completed,description \n FROM milestone WHERE name=%s\n ' 2012-10-24 16:01:20,012 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,012 Trac[util] DEBUG: args: (u'LOID 7.2',) 2012-10-24 16:01:20,013 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,016 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:20,016 Trac[util] DEBUG: args: ('ticket_type', 'optimization') 2012-10-24 16:01:20,016 Trac[util] DEBUG: SQL: '\n SELECT name,due,completed,description \n FROM milestone WHERE name=%s\n ' 2012-10-24 16:01:20,016 Trac[util] DEBUG: args: (u'Crossings Future Release',) 2012-10-24 16:01:20,020 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,022 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,022 Trac[xml_rpc] DEBUG: RPC(xml) 'system.multicall' result: [(u'1',), (u'2',), (u'3',), (u'4',)] 2012-10-24 16:01:20,023 Trac[util] DEBUG: SQL: '\n SELECT name,due,completed,description \n FROM milestone WHERE name=%s\n ' 2012-10-24 16:01:20,023 Trac[util] DEBUG: args: (u'Future Roadmap',) 2012-10-24 16:01:20,025 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,026 Trac[perm] DEBUG: No policy allowed tylerlyn performing TRAC_ADMIN on 2012-10-24 16:01:20,026 Trac[perm] DEBUG: No policy allowed tylerlyn performing TRAC_ADMIN on 2012-10-24 16:01:20,026 Trac[perm] DEBUG: No policy allowed tylerlyn performing TRAC_ADMIN on 2012-10-24 16:01:20,026 Trac[perm] DEBUG: No policy allowed tylerlyn performing TRAC_ADMIN on 2012-10-24 16:01:20,028 Trac[perm] DEBUG: No policy allowed tylerlyn performing TRAC_ADMIN on 2012-10-24 16:01:20,028 Trac[perm] DEBUG: No policy allowed tylerlyn performing TRAC_ADMIN on 2012-10-24 16:01:20,029 Trac[perm] DEBUG: No policy allowed tylerlyn performing TRAC_ADMIN on 2012-10-24 16:01:20,029 Trac[perm] DEBUG: No policy allowed tylerlyn performing MILESTONE_CREATE on 2012-10-24 16:01:20,076 Trac[chrome] DEBUG: Prepare chrome data for request 2012-10-24 16:01:20,092 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:20,095 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:20,096 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:20,096 Trac[perm] DEBUG: No policy allowed tylerlyn performing ACCTMGR_CONFIG_ADMIN on None 2012-10-24 16:01:20,098 Trac[perm] DEBUG: No policy allowed tylerlyn performing ACCTMGR_USER_ADMIN on None 2012-10-24 16:01:20,098 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:20,098 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,098 Trac[perm] DEBUG: No policy allowed tylerlyn performing TT_ADMIN on None 2012-10-24 16:01:20,099 Trac[perm] DEBUG: No policy allowed tylerlyn performing PERMISSION_GRANT on None 2012-10-24 16:01:20,099 Trac[perm] DEBUG: No policy allowed tylerlyn performing PERMISSION_REVOKE on None 2012-10-24 16:01:20,099 Trac[perm] DEBUG: No policy allowed tylerlyn performing TICKET_ADMIN on None 2012-10-24 16:01:20,101 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,101 Trac[perm] DEBUG: No policy allowed tylerlyn performing VERSIONCONTROL_ADMIN on None 2012-10-24 16:01:20,101 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:20,102 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,105 Trac[perm] DEBUG: No policy allowed tylerlyn performing TRAC_ADMIN on 2012-10-24 16:01:20,107 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:20,108 Trac[util] DEBUG: SQL: 'SELECT parent, child FROM subtickets WHERE parent=%s' 2012-10-24 16:01:20,108 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:20,108 Trac[util] DEBUG: args: (None,) 2012-10-24 16:01:20,109 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:20,109 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.version.getAll' with args: () 2012-10-24 16:01:20,111 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:20,111 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.version.getAll 2012-10-24 16:01:20,111 Trac[util] DEBUG: prefetch: 0 rows 2012-10-24 16:01:20,112 Trac[web_ui] DEBUG: SimpleTicket: Filtering new ticket form for tylerlyn 2012-10-24 16:01:20,115 Trac[blackmagic] DEBUG: Ticket permissions None type defect 2012-10-24 16:01:20,115 Trac[blackmagic] DEBUG: User tylerlyn has permission None 2012-10-24 16:01:20,115 Trac[blackmagic] DEBUG: Ticket permissions None type change request 2012-10-24 16:01:20,115 Trac[blackmagic] DEBUG: User tylerlyn has permission None 2012-10-24 16:01:20,117 Trac[blackmagic] DEBUG: Ticket permissions None type new feature 2012-10-24 16:01:20,117 Trac[blackmagic] DEBUG: User tylerlyn has permission None 2012-10-24 16:01:20,118 Trac[blackmagic] DEBUG: Ticket permissions None type optimization 2012-10-24 16:01:20,118 Trac[blackmagic] DEBUG: User tylerlyn has permission None 2012-10-24 16:01:20,118 Trac[util] DEBUG: SQL: 'SELECT name,time,description FROM version' 2012-10-24 16:01:20,118 Trac[perm] DEBUG: No policy allowed tylerlyn performing EMAIL_VIEW on None 2012-10-24 16:01:20,119 Trac[blackmagic] DEBUG: Permissions WORKFLOW_MANAGER 2012-10-24 16:01:20,119 Trac[blackmagic] DEBUG: Checking permission WORKFLOW_MANAGER 2012-10-24 16:01:20,119 Trac[util] DEBUG: prefetch: 0 rows 2012-10-24 16:01:20,121 Trac[perm] DEBUG: No policy allowed tylerlyn performing WORKFLOW_MANAGER on 2012-10-24 16:01:20,121 Trac[xml_rpc] DEBUG: RPC(xml) 'ticket.version.getAll' result: [] 2012-10-24 16:01:20,125 Trac[blackmagic] DEBUG: Permissions WORKFLOW_MANAGER 2012-10-24 16:01:20,125 Trac[blackmagic] DEBUG: Checking permission WORKFLOW_MANAGER 2012-10-24 16:01:20,173 Trac[blackmagic] DEBUG: Permissions WORKFLOW_MANAGER 2012-10-24 16:01:20,173 Trac[blackmagic] DEBUG: Checking permission WORKFLOW_MANAGER 2012-10-24 16:01:20,176 Trac[blackmagic] DEBUG: Permissions CUSTOM_PRIORITY 2012-10-24 16:01:20,177 Trac[blackmagic] DEBUG: Checking permission CUSTOM_PRIORITY 2012-10-24 16:01:20,177 Trac[perm] DEBUG: No policy allowed tylerlyn performing CUSTOM_PRIORITY on 2012-10-24 16:01:20,178 Trac[blackmagic] DEBUG: Permissions WORKFLOW_DEVELOPER 2012-10-24 16:01:20,180 Trac[blackmagic] DEBUG: Checking permission WORKFLOW_DEVELOPER 2012-10-24 16:01:20,180 Trac[blackmagic] DEBUG: Has WORKFLOW_DEVELOPER permission 2012-10-24 16:01:20,181 Trac[blackmagic] DEBUG: Permissions WORKFLOW_MANAGER 2012-10-24 16:01:20,183 Trac[blackmagic] DEBUG: Checking permission WORKFLOW_MANAGER 2012-10-24 16:01:20,183 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:20,187 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:20,187 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:20,188 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:20,190 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,190 Trac[blackmagic] DEBUG: Permissions WORKFLOW_MANAGER 2012-10-24 16:01:20,190 Trac[blackmagic] DEBUG: Checking permission WORKFLOW_MANAGER 2012-10-24 16:01:20,190 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,191 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:20,191 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,196 Trac[web_ui] DEBUG: GridModifyModule: filter_stream entered 2012-10-24 16:01:20,196 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:20,197 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:20,198 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:20,200 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.multicall' with args: ([],) 2012-10-24 16:01:20,203 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:20,203 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.multicall 2012-10-24 16:01:20,203 Trac[xml_rpc] DEBUG: RPC(xml) 'system.multicall' result: [] 2012-10-24 16:01:20,269 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:20,272 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:20,273 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:20,273 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:20,275 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,276 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,276 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:20,276 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,282 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:20,283 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:20,285 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:20,286 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.getTicketFields' with args: () 2012-10-24 16:01:20,286 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:20,288 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.getTicketFields 2012-10-24 16:01:20,292 Trac[util] DEBUG: SQL: 'SELECT id,generation FROM cache' 2012-10-24 16:01:20,296 Trac[util] DEBUG: prefetch: 10 rows 2012-10-24 16:01:20,302 Trac[xml_rpc] DEBUG: RPC(xml) 'ticket.getTicketFields' result: [{'type': 'text', 'name': 'summary', 'label': 'Summary'}, {'type': 'text', 'name': 'reporter', 'label': 'Reporter'}, {'type': 'text', 'name': 'owner', 'label': 'Owner'}, {'type': 'textarea', 'name': 'description', 'label': 'Description'}, {'label': 'Type', 'type': 'select', 'options': [u'defect', u'change request', u'new feature', u'optimization'], 'value': u'defect', 'name': 'type'}, {'name': 'status', 'type': 'radio', 'value': '', 'label': 'Status', 'optional': True, 'options': [u'Implementation', u'More Information', u'Requires Budget', u'Triage', u'Verification', u'closed', 'new']}, {'label': 'Priority', 'type': 'select', 'options': [u'urgent', u'high', u'medium', u'low'], 'value': u'low', 'name': 'priority'}, {'name': 'milestone', 'type': 'select', 'value': u'', 'label': 'Milestone', 'optional': True, 'options': [u'LOID 7.0', u'Crossings 1.0', u'LOID 7.1', u'LOID 7.2', u'Crossings Future Release', u'Future Roadmap']}, {'label': 'Component', 'type': 'select', 'options': [u'Issue Tracker', u'LOID 7', u'LOID 8'], 'value': u'LOID 7', 'name': 'component'}, {'label': 'Severity', 'type': 'select', 'options': [u'blocker', u'critical', u'important', u'minor', u'trivial'], 'value': u'trivial', 'name': 'severity'}, {'name': 'resolution', 'type': 'radio', 'value': u'none', 'label': 'Resolution', 'optional': True, 'options': [u'none', u'fixed', u'unable to reproduce', u'duplicate', u'works as intended', u'budget declined', u'out of scope']}, {'type': 'text', 'name': 'keywords', 'label': 'Keywords'}, {'type': 'text', 'name': 'cc', 'label': 'Cc'}, {'type': 'time', 'name': 'time', 'label': 'Created'}, {'type': 'time', 'name': 'changetime', 'label': 'Modified'}, {'name': u'external_priority', 'value': u'low', 'label': u'3rd Party Priority', 'options': [u'urgent', u'high', u'medium', u'low'], 'custom': True, 'type': u'select', 'order': 0}, {'name': u'parents', 'format': 'plain', 'value': '', 'custom': True, 'label': u'Parent Tickets', 'type': u'text', 'order': 0}, {'name': u'reported_version', 'format': 'plain', 'value': '', 'custom': True, 'label': u'Version', 'type': u'text', 'order': 0}] 2012-10-24 16:01:20,378 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:20,381 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:20,381 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:20,382 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:20,384 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,385 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,385 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:20,387 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,390 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:20,391 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:20,391 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:20,392 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.getAPIVersion' with args: () 2012-10-24 16:01:20,394 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:20,394 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.getAPIVersion 2012-10-24 16:01:20,397 Trac[xml_rpc] DEBUG: RPC(xml) 'system.getAPIVersion' result: [1, 1, 2] 2012-10-24 16:01:20,461 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:20,463 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:20,464 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:20,467 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:20,467 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,469 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,469 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:20,470 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,476 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:20,476 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:20,477 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:20,479 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.getAPIVersion' with args: () 2012-10-24 16:01:20,480 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:20,480 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.getAPIVersion 2012-10-24 16:01:20,483 Trac[xml_rpc] DEBUG: RPC(xml) 'system.getAPIVersion' result: [1, 1, 2] 2012-10-24 16:01:20,548 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:20,551 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:20,552 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:20,552 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:20,553 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,555 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,555 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:20,556 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,559 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:20,562 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:20,562 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:20,562 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.get' with args: (2147483647,) 2012-10-24 16:01:20,565 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:20,565 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.get 2012-10-24 16:01:20,569 Trac[util] DEBUG: SQL: 'SELECT id,generation FROM cache' 2012-10-24 16:01:20,573 Trac[util] DEBUG: prefetch: 10 rows 2012-10-24 16:01:20,581 Trac[util] DEBUG: SQL: 'SELECT summary,reporter,owner,description,type,status,priority,milestone,component,severity,resolution,keywords,cc,time,changetime FROM ticket WHERE id=%s' 2012-10-24 16:01:20,581 Trac[util] DEBUG: args: (2147483647,) 2012-10-24 16:01:20,582 Trac[util] DEBUG: prefetch: 0 rows 2012-10-24 16:01:20,584 Trac[web_ui] ERROR: RPC(XML-RPC) Error Traceback (most recent call last): File "build\bdist.win32\egg\tracrpc\web_ui.py", line 158, in _rpc_process result = (XMLRPCSystem(self.env).get_method(method_name)(req, args))[0] File "build\bdist.win32\egg\tracrpc\api.py", line 197, in __call__ result = self.callable(req, *args) File "build\bdist.win32\egg\tracrpc\ticket.py", line 149, in get t = model.Ticket(self.env, id) File "C:\Program Files (x86)\VisualSVN Server\trac\python\lib\site-packages\trac-0.12.2-py2.6-win32.egg\trac\ticket\model.py", line 68, in __init__ self._fetch_ticket(tkt_id, db) File "C:\Program Files (x86)\VisualSVN Server\trac\python\lib\site-packages\trac-0.12.2-py2.6-win32.egg\trac\ticket\model.py", line 115, in _fetch_ticket id=tkt_id), _('Invalid ticket number')) ResourceNotFound: Ticket 2147483647 does not exist. 2012-10-24 16:01:20,663 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:20,667 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:20,667 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:20,668 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:20,670 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,671 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,671 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:20,673 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,678 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:20,680 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:20,680 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:20,681 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.component.getAll' with args: () 2012-10-24 16:01:20,684 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:20,684 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.component.getAll 2012-10-24 16:01:20,691 Trac[util] DEBUG: SQL: '\n SELECT name,owner,description FROM component ORDER BY name\n ' 2012-10-24 16:01:20,694 Trac[util] DEBUG: prefetch: 3 rows 2012-10-24 16:01:20,696 Trac[xml_rpc] DEBUG: RPC(xml) 'ticket.component.getAll' result: [u'Issue Tracker', u'LOID 7', u'LOID 8'] 2012-10-24 16:01:20,760 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:20,765 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:20,766 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:20,766 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:20,766 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,769 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,769 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:20,769 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,776 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:20,778 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:20,778 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:20,779 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.multicall' with args: ([{'methodName': 'ticket.component.get', 'params': ['Issue Tracker']}, {'methodName': 'ticket.component.get', 'params': ['LOID 7']}, {'methodName': 'ticket.component.get', 'params': ['LOID 8']}],) 2012-10-24 16:01:20,782 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:20,782 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.multicall 2012-10-24 16:01:20,789 Trac[util] DEBUG: SQL: '\n SELECT owner,description FROM component WHERE name=%s\n ' 2012-10-24 16:01:20,789 Trac[util] DEBUG: args: ('Issue Tracker',) 2012-10-24 16:01:20,792 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,796 Trac[util] DEBUG: SQL: '\n SELECT owner,description FROM component WHERE name=%s\n ' 2012-10-24 16:01:20,796 Trac[util] DEBUG: args: ('LOID 7',) 2012-10-24 16:01:20,799 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,803 Trac[util] DEBUG: SQL: '\n SELECT owner,description FROM component WHERE name=%s\n ' 2012-10-24 16:01:20,805 Trac[util] DEBUG: args: ('LOID 8',) 2012-10-24 16:01:20,805 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,806 Trac[xml_rpc] DEBUG: RPC(xml) 'system.multicall' result: [({'owner': '', 'name': 'Issue Tracker', 'description': ''},), ({'owner': '', 'name': 'LOID 7', 'description': ''},), ({'owner': '', 'name': 'LOID 8', 'description': ''},)] 2012-10-24 16:01:20,869 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:20,872 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:20,874 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:20,875 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:20,877 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,878 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,878 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:20,880 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,884 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:20,887 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:20,888 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:20,888 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.milestone.getAll' with args: () 2012-10-24 16:01:20,891 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:20,891 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.milestone.getAll 2012-10-24 16:01:20,900 Trac[util] DEBUG: SQL: 'SELECT name,due,completed,description FROM milestone ' 2012-10-24 16:01:20,904 Trac[util] DEBUG: prefetch: 6 rows 2012-10-24 16:01:20,905 Trac[xml_rpc] DEBUG: RPC(xml) 'ticket.milestone.getAll' result: [u'LOID 7.0', u'Crossings 1.0', u'LOID 7.1', u'LOID 7.2', u'Crossings Future Release', u'Future Roadmap'] 2012-10-24 16:01:20,970 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:20,973 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:20,973 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:20,976 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:20,976 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,977 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:20,979 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:20,980 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:20,983 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:20,986 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:20,986 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:20,990 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.multicall' with args: ([{'methodName': 'ticket.milestone.get', 'params': ['LOID 7.0']}, {'methodName': 'ticket.milestone.get', 'params': ['Crossings 1.0']}, {'methodName': 'ticket.milestone.get', 'params': ['LOID 7.1']}, {'methodName': 'ticket.milestone.get', 'params': ['LOID 7.2']}, {'methodName': 'ticket.milestone.get', 'params': ['Crossings Future Release']}, {'methodName': 'ticket.milestone.get', 'params': ['Future Roadmap']}],) 2012-10-24 16:01:20,992 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:20,992 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.multicall 2012-10-24 16:01:20,999 Trac[util] DEBUG: SQL: '\n SELECT name,due,completed,description \n FROM milestone WHERE name=%s\n ' 2012-10-24 16:01:21,000 Trac[util] DEBUG: args: ('LOID 7.0',) 2012-10-24 16:01:21,002 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,009 Trac[util] DEBUG: SQL: '\n SELECT name,due,completed,description \n FROM milestone WHERE name=%s\n ' 2012-10-24 16:01:21,009 Trac[util] DEBUG: args: ('Crossings 1.0',) 2012-10-24 16:01:21,012 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,019 Trac[util] DEBUG: SQL: '\n SELECT name,due,completed,description \n FROM milestone WHERE name=%s\n ' 2012-10-24 16:01:21,019 Trac[util] DEBUG: args: ('LOID 7.1',) 2012-10-24 16:01:21,023 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,029 Trac[util] DEBUG: SQL: '\n SELECT name,due,completed,description \n FROM milestone WHERE name=%s\n ' 2012-10-24 16:01:21,029 Trac[util] DEBUG: args: ('LOID 7.2',) 2012-10-24 16:01:21,032 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,040 Trac[util] DEBUG: SQL: '\n SELECT name,due,completed,description \n FROM milestone WHERE name=%s\n ' 2012-10-24 16:01:21,042 Trac[util] DEBUG: args: ('Crossings Future Release',) 2012-10-24 16:01:21,042 Trac[perm] DEBUG: No policy allowed tylerlyn performing TICKET_EDIT_DESCRIPTION on 2012-10-24 16:01:21,043 Trac[perm] DEBUG: No policy allowed tylerlyn performing TICKET_ADMIN on 2012-10-24 16:01:21,043 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,046 Trac[perm] DEBUG: No policy allowed tylerlyn performing TICKET_EDIT_COMMENT on 2012-10-24 16:01:21,052 Trac[util] DEBUG: SQL: '\n SELECT name,due,completed,description \n FROM milestone WHERE name=%s\n ' 2012-10-24 16:01:21,052 Trac[util] DEBUG: args: ('Future Roadmap',) 2012-10-24 16:01:21,055 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,056 Trac[xml_rpc] DEBUG: RPC(xml) 'system.multicall' result: [({'due': datetime.datetime(2012, 7, 14, 22, 0, tzinfo=), 'completed': datetime.datetime(2012, 7, 21, 22, 0, tzinfo=), 'description': u'The goal is to fix all critical bugs and deliver to Enbridge a stable working release of LOID.', 'name': u'LOID 7.0'},), ({'due': datetime.datetime(2012, 8, 17, 22, 0, tzinfo=), 'completed': 0, 'description': u'Initial crossings release.', 'name': u'Crossings 1.0'},), ({'due': datetime.datetime(2012, 11, 15, 23, 0, tzinfo=), 'completed': 0, 'description': u'The goal here is to redesign and implement a new database for LOID while creating the least amount of impact on the front-end application. We will do the minimum to restore existing functionality to the front-end.', 'name': u'LOID 7.1'},), ({'due': datetime.datetime(2012, 12, 1, 23, 0, tzinfo=), 'completed': 0, 'description': u'The goal of this phase is to begin leveraging the back-end changes that have occurred in the LOID 7.0/7.1 to implement better data validation in the front-end.', 'name': u'LOID 7.2'},), ({'due': 0, 'completed': 0, 'description': u'The next planned release of Crossings will be to address improvements and changes to the initial release.', 'name': u'Crossings Future Release'},), ({'due': 0, 'completed': 0, 'description': u"This milestone is used to declare that there are tentative plans to address tickets, but there aren't any concrete plans at this point to do so.", 'name': u'Future Roadmap'},)] 2012-10-24 16:01:21,122 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:21,125 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:21,127 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:21,128 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:21,128 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:21,131 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,131 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:21,131 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:21,134 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:21,137 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:21,138 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:21,138 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.priority.getAll' with args: () 2012-10-24 16:01:21,140 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:21,140 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.priority.getAll 2012-10-24 16:01:21,150 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM enum WHERE type=%s \n ORDER BY\n CAST(value AS integer)' 2012-10-24 16:01:21,150 Trac[util] DEBUG: args: ('priority',) 2012-10-24 16:01:21,153 Trac[util] DEBUG: prefetch: 4 rows 2012-10-24 16:01:21,154 Trac[xml_rpc] DEBUG: RPC(xml) 'ticket.priority.getAll' result: [u'urgent', u'high', u'medium', u'low'] 2012-10-24 16:01:21,217 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:21,220 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:21,220 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:21,221 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:21,221 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:21,224 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,224 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:21,226 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:21,232 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:21,233 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:21,233 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:21,236 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.multicall' with args: ([{'methodName': 'ticket.priority.get', 'params': ['urgent']}, {'methodName': 'ticket.priority.get', 'params': ['high']}, {'methodName': 'ticket.priority.get', 'params': ['medium']}, {'methodName': 'ticket.priority.get', 'params': ['low']}],) 2012-10-24 16:01:21,237 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:21,239 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.multicall 2012-10-24 16:01:21,247 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:21,247 Trac[util] DEBUG: args: ('priority', 'urgent') 2012-10-24 16:01:21,250 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,259 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:21,259 Trac[util] DEBUG: args: ('priority', 'high') 2012-10-24 16:01:21,262 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,269 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:21,269 Trac[util] DEBUG: args: ('priority', 'medium') 2012-10-24 16:01:21,270 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,282 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:21,282 Trac[util] DEBUG: args: ('priority', 'low') 2012-10-24 16:01:21,283 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,286 Trac[xml_rpc] DEBUG: RPC(xml) 'system.multicall' result: [(u'1',), (u'2',), (u'3',), (u'4',)] 2012-10-24 16:01:21,351 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:21,371 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:21,371 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:21,372 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:21,374 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:21,375 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,375 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:21,377 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:21,380 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:21,381 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:21,381 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:21,381 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.resolution.getAll' with args: () 2012-10-24 16:01:21,384 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:21,384 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.resolution.getAll 2012-10-24 16:01:21,391 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM enum WHERE type=%s \n ORDER BY\n CAST(value AS integer)' 2012-10-24 16:01:21,392 Trac[util] DEBUG: args: ('resolution',) 2012-10-24 16:01:21,394 Trac[util] DEBUG: prefetch: 7 rows 2012-10-24 16:01:21,395 Trac[xml_rpc] DEBUG: RPC(xml) 'ticket.resolution.getAll' result: [u'none', u'fixed', u'unable to reproduce', u'duplicate', u'works as intended', u'budget declined', u'out of scope'] 2012-10-24 16:01:21,457 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:21,461 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:21,463 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:21,463 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:21,464 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:21,464 Trac[perm] DEBUG: No policy allowed tylerlyn performing TRAC_ADMIN on 2012-10-24 16:01:21,467 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,467 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:21,467 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:21,473 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:21,476 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:21,476 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:21,480 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.multicall' with args: ([{'methodName': 'ticket.resolution.get', 'params': ['none']}, {'methodName': 'ticket.resolution.get', 'params': ['fixed']}, {'methodName': 'ticket.resolution.get', 'params': ['unable to reproduce']}, {'methodName': 'ticket.resolution.get', 'params': ['duplicate']}, {'methodName': 'ticket.resolution.get', 'params': ['works as intended']}, {'methodName': 'ticket.resolution.get', 'params': ['budget declined']}, {'methodName': 'ticket.resolution.get', 'params': ['out of scope']}],) 2012-10-24 16:01:21,482 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:21,483 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.multicall 2012-10-24 16:01:21,490 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:21,490 Trac[util] DEBUG: args: ('resolution', 'none') 2012-10-24 16:01:21,493 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,500 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:21,500 Trac[util] DEBUG: args: ('resolution', 'fixed') 2012-10-24 16:01:21,502 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,509 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:21,510 Trac[util] DEBUG: args: ('resolution', 'unable to reproduce') 2012-10-24 16:01:21,513 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,522 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:21,522 Trac[util] DEBUG: args: ('resolution', 'duplicate') 2012-10-24 16:01:21,523 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,533 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:21,535 Trac[util] DEBUG: args: ('resolution', 'works as intended') 2012-10-24 16:01:21,536 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,546 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:21,546 Trac[util] DEBUG: args: ('resolution', 'budget declined') 2012-10-24 16:01:21,549 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,558 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:21,559 Trac[util] DEBUG: args: ('resolution', 'out of scope') 2012-10-24 16:01:21,561 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,562 Trac[xml_rpc] DEBUG: RPC(xml) 'system.multicall' result: [(u'1',), (u'2',), (u'3',), (u'4',), (u'5',), (u'6',), (u'7',)] 2012-10-24 16:01:21,628 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:21,631 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:21,631 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:21,631 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:21,632 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:21,634 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,634 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:21,634 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:21,634 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:21,635 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:21,635 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:21,635 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.severity.getAll' with args: () 2012-10-24 16:01:21,637 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:21,637 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.severity.getAll 2012-10-24 16:01:21,688 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM enum WHERE type=%s \n ORDER BY\n CAST(value AS integer)' 2012-10-24 16:01:21,690 Trac[util] DEBUG: args: ('severity',) 2012-10-24 16:01:21,690 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:21,693 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:21,693 Trac[util] DEBUG: prefetch: 5 rows 2012-10-24 16:01:21,694 Trac[xml_rpc] DEBUG: RPC(xml) 'ticket.severity.getAll' result: [u'blocker', u'critical', u'important', u'minor', u'trivial'] 2012-10-24 16:01:21,694 Trac[util] DEBUG: SQL: 'SELECT name FROM auth_cookie WHERE cookie=%s' 2012-10-24 16:01:21,696 Trac[util] DEBUG: args: ('2b30711b0dacc340538567533ab6902b',) 2012-10-24 16:01:21,697 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,697 Trac[session] DEBUG: Retrieving session for ID u'tylerlyn' 2012-10-24 16:01:21,697 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:21,698 Trac[util] DEBUG: args: (u'tylerlyn', 1) 2012-10-24 16:01:21,700 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,700 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:21,700 Trac[util] DEBUG: args: (u'tylerlyn', 1) 2012-10-24 16:01:21,700 Trac[util] DEBUG: prefetch: 6 rows 2012-10-24 16:01:21,701 Trac[util] DEBUG: SQL: 'SELECT name FROM auth_cookie WHERE cookie=%s' 2012-10-24 16:01:21,701 Trac[util] DEBUG: args: ('2b30711b0dacc340538567533ab6902b',) 2012-10-24 16:01:21,744 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,746 Trac[perm] DEBUG: No policy allowed tylerlyn performing TRAC_ADMIN on None 2012-10-24 16:01:21,759 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:21,759 Trac[chrome] DEBUG: Prepare chrome data for request 2012-10-24 16:01:21,763 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:21,763 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:21,766 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:21,766 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:21,769 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,769 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:21,769 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:21,770 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:21,772 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:21,773 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:21,775 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.multicall' with args: ([{'methodName': 'ticket.severity.get', 'params': ['blocker']}, {'methodName': 'ticket.severity.get', 'params': ['critical']}, {'methodName': 'ticket.severity.get', 'params': ['important']}, {'methodName': 'ticket.severity.get', 'params': ['minor']}, {'methodName': 'ticket.severity.get', 'params': ['trivial']}],) 2012-10-24 16:01:21,776 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:21,776 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.multicall 2012-10-24 16:01:21,785 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:21,786 Trac[util] DEBUG: args: ('severity', 'blocker') 2012-10-24 16:01:21,786 Trac[perm] DEBUG: No policy allowed tylerlyn performing ACCTMGR_CONFIG_ADMIN on None 2012-10-24 16:01:21,786 Trac[perm] DEBUG: No policy allowed tylerlyn performing ACCTMGR_USER_ADMIN on None 2012-10-24 16:01:21,786 Trac[perm] DEBUG: No policy allowed tylerlyn performing TT_ADMIN on None 2012-10-24 16:01:21,788 Trac[perm] DEBUG: No policy allowed tylerlyn performing PERMISSION_GRANT on None 2012-10-24 16:01:21,788 Trac[perm] DEBUG: No policy allowed tylerlyn performing PERMISSION_REVOKE on None 2012-10-24 16:01:21,788 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,789 Trac[perm] DEBUG: No policy allowed tylerlyn performing TICKET_ADMIN on None 2012-10-24 16:01:21,789 Trac[perm] DEBUG: No policy allowed tylerlyn performing VERSIONCONTROL_ADMIN on None 2012-10-24 16:01:21,795 Trac[perm] DEBUG: No policy allowed tylerlyn performing TRAC_ADMIN on 2012-10-24 16:01:21,798 Trac[perm] DEBUG: No policy allowed tylerlyn performing EMAIL_VIEW on None 2012-10-24 16:01:21,799 Trac[web_ui] DEBUG: GridModifyModule: filter_stream entered 2012-10-24 16:01:21,799 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:21,801 Trac[util] DEBUG: args: ('severity', 'critical') 2012-10-24 16:01:21,802 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,805 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:21,805 Trac[util] DEBUG: args: ('severity', 'important') 2012-10-24 16:01:21,806 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,809 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:21,809 Trac[util] DEBUG: args: ('severity', 'minor') 2012-10-24 16:01:21,811 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,813 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:21,813 Trac[util] DEBUG: args: ('severity', 'trivial') 2012-10-24 16:01:21,861 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,862 Trac[xml_rpc] DEBUG: RPC(xml) 'system.multicall' result: [(u'1',), (u'2',), (u'3',), (u'4',), (u'5',)] 2012-10-24 16:01:21,865 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:21,865 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:21,867 Trac[util] DEBUG: SQL: 'SELECT name FROM auth_cookie WHERE cookie=%s' 2012-10-24 16:01:21,867 Trac[util] DEBUG: args: ('2b30711b0dacc340538567533ab6902b',) 2012-10-24 16:01:21,911 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,913 Trac[session] DEBUG: Retrieving session for ID u'tylerlyn' 2012-10-24 16:01:21,914 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:21,914 Trac[util] DEBUG: args: (u'tylerlyn', 1) 2012-10-24 16:01:21,915 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,917 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:21,917 Trac[util] DEBUG: args: (u'tylerlyn', 1) 2012-10-24 16:01:21,917 Trac[util] DEBUG: prefetch: 6 rows 2012-10-24 16:01:21,918 Trac[util] DEBUG: SQL: 'SELECT name FROM auth_cookie WHERE cookie=%s' 2012-10-24 16:01:21,918 Trac[util] DEBUG: args: ('2b30711b0dacc340538567533ab6902b',) 2012-10-24 16:01:21,921 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:21,921 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,924 Trac[perm] DEBUG: No policy allowed tylerlyn performing TRAC_ADMIN on None 2012-10-24 16:01:21,924 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:21,926 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:21,927 Trac[util] DEBUG: SQL: 'SELECT tt_name, tt_field, tt_value \n FROM ticket_template_store \n WHERE tt_user = %s\n ' 2012-10-24 16:01:21,927 Trac[util] DEBUG: args: (u'tylerlyn',) 2012-10-24 16:01:21,927 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:21,927 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:21,933 Trac[util] DEBUG: prefetch: 0 rows 2012-10-24 16:01:21,934 Trac[util] DEBUG: SQL: 'SELECT DISTINCT tt_name \n FROM ticket_template_store\n WHERE tt_user = %s\n ' 2012-10-24 16:01:21,934 Trac[util] DEBUG: args: ('SYSTEM',) 2012-10-24 16:01:21,934 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,934 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:21,934 Trac[util] DEBUG: prefetch: 7 rows 2012-10-24 16:01:21,934 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:21,936 Trac[util] DEBUG: SQL: 'SELECT tt_field, tt_value \n FROM ticket_template_store \n WHERE tt_user = %s \n AND tt_name = %s \n AND tt_time = (SELECT max(tt_time) \n FROM ticket_template_store \n WHERE tt_name = %s)\n ' 2012-10-24 16:01:21,936 Trac[util] DEBUG: args: ('SYSTEM', u'change request', u'change request') 2012-10-24 16:01:21,937 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:21,937 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,937 Trac[util] DEBUG: SQL: 'SELECT tt_field, tt_value \n FROM ticket_template_store \n WHERE tt_user = %s \n AND tt_name = %s \n AND tt_time = (SELECT max(tt_time) \n FROM ticket_template_store \n WHERE tt_name = %s)\n ' 2012-10-24 16:01:21,937 Trac[util] DEBUG: args: ('SYSTEM', u'default', u'default') 2012-10-24 16:01:21,937 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:21,938 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,938 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:21,940 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.getAPIVersion' with args: () 2012-10-24 16:01:21,940 Trac[util] DEBUG: SQL: 'SELECT tt_field, tt_value \n FROM ticket_template_store \n WHERE tt_user = %s \n AND tt_name = %s \n AND tt_time = (SELECT max(tt_time) \n FROM ticket_template_store \n WHERE tt_name = %s)\n ' 2012-10-24 16:01:21,940 Trac[util] DEBUG: args: ('SYSTEM', u'defect', u'defect') 2012-10-24 16:01:21,941 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:21,941 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,943 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.getAPIVersion 2012-10-24 16:01:21,944 Trac[util] DEBUG: SQL: 'SELECT tt_field, tt_value \n FROM ticket_template_store \n WHERE tt_user = %s \n AND tt_name = %s \n AND tt_time = (SELECT max(tt_time) \n FROM ticket_template_store \n WHERE tt_name = %s)\n ' 2012-10-24 16:01:21,944 Trac[util] DEBUG: args: ('SYSTEM', u'enhancement', u'enhancement') 2012-10-24 16:01:21,946 Trac[xml_rpc] DEBUG: RPC(xml) 'system.getAPIVersion' result: [1, 1, 2] 2012-10-24 16:01:21,946 Trac[util] DEBUG: prefetch: 2 rows 2012-10-24 16:01:21,947 Trac[util] DEBUG: SQL: 'SELECT tt_field, tt_value \n FROM ticket_template_store \n WHERE tt_user = %s \n AND tt_name = %s \n AND tt_time = (SELECT max(tt_time) \n FROM ticket_template_store \n WHERE tt_name = %s)\n ' 2012-10-24 16:01:21,947 Trac[util] DEBUG: args: ('SYSTEM', u'new feature', u'new feature') 2012-10-24 16:01:21,947 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,947 Trac[util] DEBUG: SQL: 'SELECT tt_field, tt_value \n FROM ticket_template_store \n WHERE tt_user = %s \n AND tt_name = %s \n AND tt_time = (SELECT max(tt_time) \n FROM ticket_template_store \n WHERE tt_name = %s)\n ' 2012-10-24 16:01:21,947 Trac[util] DEBUG: args: ('SYSTEM', u'optimization', u'optimization') 2012-10-24 16:01:21,948 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:21,948 Trac[util] DEBUG: SQL: 'SELECT tt_field, tt_value \n FROM ticket_template_store \n WHERE tt_user = %s \n AND tt_name = %s \n AND tt_time = (SELECT max(tt_time) \n FROM ticket_template_store \n WHERE tt_name = %s)\n ' 2012-10-24 16:01:21,948 Trac[util] DEBUG: args: ('SYSTEM', u'task', u'task') 2012-10-24 16:01:21,948 Trac[util] DEBUG: prefetch: 2 rows 2012-10-24 16:01:21,950 Trac[perm] DEBUG: No policy allowed tylerlyn performing TT_USER on None 2012-10-24 16:01:22,010 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:22,013 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:22,013 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:22,013 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:22,013 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,065 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:22,065 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:22,065 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,065 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:22,065 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:22,065 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:22,066 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.status.getAll' with args: () 2012-10-24 16:01:22,066 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:22,066 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.status.getAll 2012-10-24 16:01:22,068 Trac[xml_rpc] DEBUG: RPC(xml) 'ticket.status.getAll' result: [u'Implementation', u'More Information', u'Requires Budget', u'Triage', u'Verification', u'closed', 'new'] 2012-10-24 16:01:22,134 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:22,134 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:22,134 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:22,135 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:22,135 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,137 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:22,137 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:22,138 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,138 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:22,138 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:22,138 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:22,140 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.multicall' with args: ([{'methodName': 'ticket.status.get', 'params': ['Implementation']}, {'methodName': 'ticket.status.get', 'params': ['More Information']}, {'methodName': 'ticket.status.get', 'params': ['Requires Budget']}, {'methodName': 'ticket.status.get', 'params': ['Triage']}, {'methodName': 'ticket.status.get', 'params': ['Verification']}, {'methodName': 'ticket.status.get', 'params': ['closed']}, {'methodName': 'ticket.status.get', 'params': ['new']}],) 2012-10-24 16:01:22,141 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:22,141 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.multicall 2012-10-24 16:01:22,145 Trac[xml_rpc] DEBUG: RPC(xml) 'system.multicall' result: [('0',), ('0',), ('0',), ('0',), ('0',), ('0',), ('0',)] 2012-10-24 16:01:22,204 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:22,206 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:22,206 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:22,207 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:22,207 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,209 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:22,209 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:22,209 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,209 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:22,210 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:22,210 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:22,210 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.type.getAll' with args: () 2012-10-24 16:01:22,210 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:22,210 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.type.getAll 2012-10-24 16:01:22,213 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM enum WHERE type=%s \n ORDER BY\n CAST(value AS integer)' 2012-10-24 16:01:22,213 Trac[util] DEBUG: args: ('ticket_type',) 2012-10-24 16:01:22,213 Trac[util] DEBUG: prefetch: 4 rows 2012-10-24 16:01:22,214 Trac[xml_rpc] DEBUG: RPC(xml) 'ticket.type.getAll' result: [u'defect', u'change request', u'new feature', u'optimization'] 2012-10-24 16:01:22,279 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:22,280 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:22,280 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:22,282 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:22,282 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,282 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:22,282 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:22,282 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,283 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:22,283 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:22,283 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:22,285 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.multicall' with args: ([{'methodName': 'ticket.type.get', 'params': ['defect']}, {'methodName': 'ticket.type.get', 'params': ['change request']}, {'methodName': 'ticket.type.get', 'params': ['new feature']}, {'methodName': 'ticket.type.get', 'params': ['optimization']}],) 2012-10-24 16:01:22,286 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:22,286 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.multicall 2012-10-24 16:01:22,288 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:22,288 Trac[util] DEBUG: args: ('ticket_type', 'defect') 2012-10-24 16:01:22,289 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:22,290 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:22,290 Trac[util] DEBUG: args: ('ticket_type', 'change request') 2012-10-24 16:01:22,292 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:22,293 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:22,293 Trac[util] DEBUG: args: ('ticket_type', 'new feature') 2012-10-24 16:01:22,295 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:22,296 Trac[util] DEBUG: SQL: 'SELECT value FROM enum WHERE type=%s AND name=%s' 2012-10-24 16:01:22,296 Trac[util] DEBUG: args: ('ticket_type', 'optimization') 2012-10-24 16:01:22,298 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:22,299 Trac[xml_rpc] DEBUG: RPC(xml) 'system.multicall' result: [(u'1',), (u'2',), (u'3',), (u'4',)] 2012-10-24 16:01:22,362 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:22,365 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:22,365 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:22,365 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:22,365 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,367 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:22,367 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:22,367 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,368 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:22,368 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:22,368 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:22,368 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.version.getAll' with args: () 2012-10-24 16:01:22,368 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:22,368 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.version.getAll 2012-10-24 16:01:22,371 Trac[util] DEBUG: SQL: 'SELECT name,time,description FROM version' 2012-10-24 16:01:22,371 Trac[util] DEBUG: prefetch: 0 rows 2012-10-24 16:01:22,372 Trac[xml_rpc] DEBUG: RPC(xml) 'ticket.version.getAll' result: [] 2012-10-24 16:01:22,433 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:22,434 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:22,434 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:22,436 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:22,436 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,437 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:22,437 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:22,437 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,437 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:22,437 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:22,437 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:22,438 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.multicall' with args: ([],) 2012-10-24 16:01:22,438 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:22,438 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.multicall 2012-10-24 16:01:22,440 Trac[xml_rpc] DEBUG: RPC(xml) 'system.multicall' result: [] 2012-10-24 16:01:22,503 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:22,506 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:22,506 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:22,506 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:22,506 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,507 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:22,507 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:22,507 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,509 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:22,509 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:22,509 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:22,509 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.getTicketFields' with args: () 2012-10-24 16:01:22,509 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:22,509 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.getTicketFields 2012-10-24 16:01:22,512 Trac[util] DEBUG: SQL: 'SELECT id,generation FROM cache' 2012-10-24 16:01:22,513 Trac[util] DEBUG: prefetch: 10 rows 2012-10-24 16:01:22,513 Trac[xml_rpc] DEBUG: RPC(xml) 'ticket.getTicketFields' result: [{'type': 'text', 'name': 'summary', 'label': 'Summary'}, {'type': 'text', 'name': 'reporter', 'label': 'Reporter'}, {'type': 'text', 'name': 'owner', 'label': 'Owner'}, {'type': 'textarea', 'name': 'description', 'label': 'Description'}, {'label': 'Type', 'type': 'select', 'options': [u'defect', u'change request', u'new feature', u'optimization'], 'value': u'defect', 'name': 'type'}, {'name': 'status', 'type': 'radio', 'value': '', 'label': 'Status', 'optional': True, 'options': [u'Implementation', u'More Information', u'Requires Budget', u'Triage', u'Verification', u'closed', 'new']}, {'label': 'Priority', 'type': 'select', 'options': [u'urgent', u'high', u'medium', u'low'], 'value': u'low', 'name': 'priority'}, {'name': 'milestone', 'type': 'select', 'value': u'', 'label': 'Milestone', 'optional': True, 'options': [u'LOID 7.0', u'Crossings 1.0', u'LOID 7.1', u'LOID 7.2', u'Crossings Future Release', u'Future Roadmap']}, {'label': 'Component', 'type': 'select', 'options': [u'Issue Tracker', u'LOID 7', u'LOID 8'], 'value': u'LOID 7', 'name': 'component'}, {'label': 'Severity', 'type': 'select', 'options': [u'blocker', u'critical', u'important', u'minor', u'trivial'], 'value': u'trivial', 'name': 'severity'}, {'name': 'resolution', 'type': 'radio', 'value': u'none', 'label': 'Resolution', 'optional': True, 'options': [u'none', u'fixed', u'unable to reproduce', u'duplicate', u'works as intended', u'budget declined', u'out of scope']}, {'type': 'text', 'name': 'keywords', 'label': 'Keywords'}, {'type': 'text', 'name': 'cc', 'label': 'Cc'}, {'type': 'time', 'name': 'time', 'label': 'Created'}, {'type': 'time', 'name': 'changetime', 'label': 'Modified'}, {'name': u'external_priority', 'value': u'low', 'label': u'3rd Party Priority', 'options': [u'urgent', u'high', u'medium', u'low'], 'custom': True, 'type': u'select', 'order': 0}, {'name': u'parents', 'format': 'plain', 'value': '', 'custom': True, 'label': u'Parent Tickets', 'type': u'text', 'order': 0}, {'name': u'reported_version', 'format': 'plain', 'value': '', 'custom': True, 'label': u'Version', 'type': u'text', 'order': 0}] 2012-10-24 16:01:22,582 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:22,584 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:22,585 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:22,585 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:22,585 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,586 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:22,586 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:22,586 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,586 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:22,588 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:22,588 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:22,588 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.getAPIVersion' with args: () 2012-10-24 16:01:22,588 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:22,588 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.getAPIVersion 2012-10-24 16:01:22,588 Trac[xml_rpc] DEBUG: RPC(xml) 'system.getAPIVersion' result: [1, 1, 2] 2012-10-24 16:01:22,648 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:22,651 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:22,651 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:22,651 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:22,651 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,653 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:22,653 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:22,653 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,654 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:22,654 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:22,654 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:22,654 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.getAPIVersion' with args: () 2012-10-24 16:01:22,654 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:22,654 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.getAPIVersion 2012-10-24 16:01:22,655 Trac[xml_rpc] DEBUG: RPC(xml) 'system.getAPIVersion' result: [1, 1, 2] 2012-10-24 16:01:22,719 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:22,720 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:22,720 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:22,720 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:22,720 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,723 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:22,723 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:22,723 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,723 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:22,723 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:22,723 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:22,723 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.get' with args: (2147483647,) 2012-10-24 16:01:22,724 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:22,724 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.get 2012-10-24 16:01:22,726 Trac[util] DEBUG: SQL: 'SELECT id,generation FROM cache' 2012-10-24 16:01:22,726 Trac[util] DEBUG: prefetch: 10 rows 2012-10-24 16:01:22,727 Trac[util] DEBUG: SQL: 'SELECT summary,reporter,owner,description,type,status,priority,milestone,component,severity,resolution,keywords,cc,time,changetime FROM ticket WHERE id=%s' 2012-10-24 16:01:22,729 Trac[util] DEBUG: args: (2147483647,) 2012-10-24 16:01:22,730 Trac[util] DEBUG: prefetch: 0 rows 2012-10-24 16:01:22,730 Trac[web_ui] ERROR: RPC(XML-RPC) Error Traceback (most recent call last): File "build\bdist.win32\egg\tracrpc\web_ui.py", line 158, in _rpc_process result = (XMLRPCSystem(self.env).get_method(method_name)(req, args))[0] File "build\bdist.win32\egg\tracrpc\api.py", line 197, in __call__ result = self.callable(req, *args) File "build\bdist.win32\egg\tracrpc\ticket.py", line 149, in get t = model.Ticket(self.env, id) File "C:\Program Files (x86)\VisualSVN Server\trac\python\lib\site-packages\trac-0.12.2-py2.6-win32.egg\trac\ticket\model.py", line 68, in __init__ self._fetch_ticket(tkt_id, db) File "C:\Program Files (x86)\VisualSVN Server\trac\python\lib\site-packages\trac-0.12.2-py2.6-win32.egg\trac\ticket\model.py", line 115, in _fetch_ticket id=tkt_id), _('Invalid ticket number')) ResourceNotFound: Ticket 2147483647 does not exist. 2012-10-24 16:01:22,792 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:22,795 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:22,795 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:22,796 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:22,796 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,796 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:22,796 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:22,796 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,798 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:22,798 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:22,799 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:22,799 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.getAPIVersion' with args: () 2012-10-24 16:01:22,799 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:22,799 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.getAPIVersion 2012-10-24 16:01:22,799 Trac[xml_rpc] DEBUG: RPC(xml) 'system.getAPIVersion' result: [1, 1, 2] 2012-10-24 16:01:22,855 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:22,857 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:22,858 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:22,858 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:22,858 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,859 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:22,859 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:22,859 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,859 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:22,861 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:22,861 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:22,861 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'system.getAPIVersion' with args: () 2012-10-24 16:01:22,861 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:22,861 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' system.getAPIVersion 2012-10-24 16:01:22,861 Trac[xml_rpc] DEBUG: RPC(xml) 'system.getAPIVersion' result: [1, 1, 2] 2012-10-24 16:01:22,917 Trac[main] DEBUG: Dispatching 2012-10-24 16:01:22,920 Trac[api] INFO: Synchronized '' repository in 0.00 seconds 2012-10-24 16:01:22,920 Trac[session] DEBUG: Retrieving session for ID 'chrismckee' 2012-10-24 16:01:22,921 Trac[util] DEBUG: SQL: '\n SELECT last_visit FROM session WHERE sid=%s AND authenticated=%s\n ' 2012-10-24 16:01:22,921 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,921 Trac[util] DEBUG: prefetch: 1 rows 2012-10-24 16:01:22,921 Trac[util] DEBUG: SQL: '\n SELECT name,value FROM session_attribute\n WHERE sid=%s and authenticated=%s\n ' 2012-10-24 16:01:22,921 Trac[util] DEBUG: args: ('chrismckee', 1) 2012-10-24 16:01:22,923 Trac[util] DEBUG: prefetch: 19 rows 2012-10-24 16:01:22,923 Trac[web_ui] DEBUG: RPC incoming request of content type 'text/xml' dispatched to 2012-10-24 16:01:22,923 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' 2012-10-24 16:01:22,924 Trac[xml_rpc] DEBUG: RPC(xml) call by 'chrismckee', method 'ticket.get' with args: (2147483647,) 2012-10-24 16:01:22,924 Trac[perm] DEBUG: No policy allowed chrismckee performing TRAC_ADMIN on None 2012-10-24 16:01:22,924 Trac[web_ui] DEBUG: RPC(XML-RPC) call by 'chrismckee' ticket.get 2012-10-24 16:01:22,924 Trac[util] DEBUG: SQL: 'SELECT id,generation FROM cache' 2012-10-24 16:01:22,927 Trac[util] DEBUG: prefetch: 10 rows 2012-10-24 16:01:22,927 Trac[util] DEBUG: SQL: 'SELECT summary,reporter,owner,description,type,status,priority,milestone,component,severity,resolution,keywords,cc,time,changetime FROM ticket WHERE id=%s' 2012-10-24 16:01:22,927 Trac[util] DEBUG: args: (2147483647,) 2012-10-24 16:01:22,928 Trac[util] DEBUG: prefetch: 0 rows 2012-10-24 16:01:22,928 Trac[web_ui] ERROR: RPC(XML-RPC) Error Traceback (most recent call last): File "build\bdist.win32\egg\tracrpc\web_ui.py", line 158, in _rpc_process result = (XMLRPCSystem(self.env).get_method(method_name)(req, args))[0] File "build\bdist.win32\egg\tracrpc\api.py", line 197, in __call__ result = self.callable(req, *args) File "build\bdist.win32\egg\tracrpc\ticket.py", line 149, in get t = model.Ticket(self.env, id) File "C:\Program Files (x86)\VisualSVN Server\trac\python\lib\site-packages\trac-0.12.2-py2.6-win32.egg\trac\ticket\model.py", line 68, in __init__ self._fetch_ticket(tkt_id, db) File "C:\Program Files (x86)\VisualSVN Server\trac\python\lib\site-packages\trac-0.12.2-py2.6-win32.egg\trac\ticket\model.py", line 115, in _fetch_ticket id=tkt_id), _('Invalid ticket number')) ResourceNotFound: Ticket 2147483647 does not exist.