FWIW, here's a DEBUG log from an exemplary ticket creation (New User
Story).
Any ideas?
All the best,
/k
2009-10-08 09:55:58,823 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'fields'
2009-10-08 09:55:58,825 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'changes'
2009-10-08 09:55:58,826 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'available_types'
2009-10-08 09:55:58,829 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'allowed_links'
2009-10-08 09:55:58,830 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'source'
2009-10-08 09:55:58,830 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'ticket_types'
2009-10-08 09:55:58,831 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'target'
2009-10-08 09:55:58,831 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
story, Alias: User Story Permission: AGILO_CREATE_STORY
2009-10-08 09:55:58,832 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
task, Alias: Task Permission: AGILO_CREATE_TASK
2009-10-08 09:55:58,832 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
requirement, Alias: Requirement Permission: AGILO_CREATE_REQUIREMENT
2009-10-08 09:55:58,833 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
incident, Alias: Support Incident Permission: CREATE_INCIDENT
2009-10-08 09:55:58,833 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
recurring, Alias: Recurring Permission: CREATE_RECURRING
2009-10-08 09:55:58,834 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
bug, Alias: Bug Permission: AGILO_CREATE_BUG
2009-10-08 09:55:58,834 Trac[log] DEBUG: [CoreTemplateProvider]:
krohrbac has create permission for types: [u'story', u'task',
u'requirement', u'incident', u'recurring', u'bug']
2009-10-08 09:55:58,835 Trac[session] DEBUG: Retrieving session for ID
'krohrbac'
2009-10-08 09:56:15,965 Trac[main] DEBUG: Dispatching <Request "POST
u'/newticket'">
2009-10-08 09:56:15,991 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Initializing with: None, None, None
2009-10-08 09:56:15,992 Trac[log] DEBUG: [AgiloTicketSystem]:
Checking: incident with: {u'story': u'User Story', u'task': u'Task',
u'requirement': u'Requirement', u'incident': u'Support Incident',
u'recurring': u'Recurring', u'bug': u'Bug'}, {u'Requirement':
u'requirement', u'Task': u'task', u'Support Incident': u'incident',
u'User Story': u'story', u'Recurring': u'recurring', u'Bug': u'bug'}
2009-10-08 09:56:15,993 Trac[log] DEBUG: [AgiloTicketSystem]:
Returning Normalized Type: incident
2009-10-08 09:56:15,993 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = None (<type 'NoneType'>)
2009-10-08 09:56:15,994 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = None (<type 'NoneType'>)
2009-10-08 09:56:15,995 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Resetting fields for type(incident): []
2009-10-08 09:56:15,995 Trac[log] DEBUG: [AgiloTicketSystem]:
Checking: incident with: {u'story': u'User Story', u'task': u'Task',
u'requirement': u'Requirement', u'incident': u'Support Incident',
u'recurring': u'Recurring', u'bug': u'Bug'}, {u'Requirement':
u'requirement', u'Task': u'task', u'Support Incident': u'incident',
u'User Story': u'story', u'Recurring': u'recurring', u'Bug': u'bug'}
2009-10-08 09:56:15,996 Trac[log] DEBUG: [AgiloTicketSystem]:
Returning Normalized Type: incident
2009-10-08 09:56:15,997 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Loaded agilo properties: CALCULATED:{} ALLOWED:{} SORT:{} SHOW:
{}
2009-10-08 09:56:15,998 Trac[log] DEBUG: [AgiloTicketSystem]:
Checking: story with: {u'story': u'User Story', u'task': u'Task',
u'requirement': u'Requirement', u'incident': u'Support Incident',
u'recurring': u'Recurring', u'bug': u'Bug'}, {u'Requirement':
u'requirement', u'Task': u'task', u'Support Incident': u'incident',
u'User Story': u'story', u'Recurring': u'recurring', u'Bug': u'bug'}
2009-10-08 09:56:15,999 Trac[log] DEBUG: [AgiloTicketSystem]:
Returning Normalized Type: story
2009-10-08 09:56:16,000 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = incident (<type 'unicode'>)
2009-10-08 09:56:16,001 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = incident (<type 'unicode'>)
2009-10-08 09:56:16,001 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Resetting fields for type(story): set(['status', 'description',
'reporter', u'cc', 'resolution', u'component', u'remaining_time',
'summary', u'priority', 'owner', u'keywords', u'o_links', 'type',
'id'])
2009-10-08 09:56:16,002 Trac[log] DEBUG: [AgiloTicketSystem]:
Checking: story with: {u'story': u'User Story', u'task': u'Task',
u'requirement': u'Requirement', u'incident': u'Support Incident',
u'recurring': u'Recurring', u'bug': u'Bug'}, {u'Requirement':
u'requirement', u'Task': u'task', u'Support Incident': u'incident',
u'User Story': u'story', u'Recurring': u'recurring', u'Bug': u'bug'}
2009-10-08 09:56:16,002 Trac[log] DEBUG: [AgiloTicketSystem]:
Returning Normalized Type: story
2009-10-08 09:56:16,003 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Loaded agilo properties: CALCULATED:{u'total_remaining_time':
<agilo.ticket.links.operators.SummingOperator object at 0x8e671cc>,
u'estimated_remaining_time':
<agilo.ticket.links.operators.MultiplicationOperator object at
0x87accac>} ALLOWED:{u'task': <agilo.ticket.links.model.AllowedLink
object at 0x8d2932c>} SORT:{u'task': []} SHOW:{u'task': [u'owner',
u'remaining_time']}
2009-10-08 09:56:16,004 Trac[log] DEBUG: [AgiloTicketSystem]:
Checking: story with: {u'story': u'User Story', u'task': u'Task',
u'requirement': u'Requirement', u'incident': u'Support Incident',
u'recurring': u'Recurring', u'bug': u'Bug'}, {u'Requirement':
u'requirement', u'Task': u'task', u'Support Incident': u'incident',
u'User Story': u'story', u'Recurring': u'recurring', u'Bug': u'bug'}
2009-10-08 09:56:16,005 Trac[log] DEBUG: [AgiloTicketSystem]:
Returning Normalized Type: story
2009-10-08 09:56:16,005 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = incident (<type 'unicode'>)
2009-10-08 09:56:16,006 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = incident (<type 'unicode'>)
2009-10-08 09:56:16,006 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Resetting fields for type(story): set(['status', 'description',
'reporter', u'cc', 'resolution', u'component', u'remaining_time',
'summary', u'priority', 'owner', u'keywords', u'o_links', 'type',
'id'])
2009-10-08 09:56:16,007 Trac[log] DEBUG: [AgiloTicketSystem]:
Checking: story with: {u'story': u'User Story', u'task': u'Task',
u'requirement': u'Requirement', u'incident': u'Support Incident',
u'recurring': u'Recurring', u'bug': u'Bug'}, {u'Requirement':
u'requirement', u'Task': u'task', u'Support Incident': u'incident',
u'User Story': u'story', u'Recurring': u'recurring', u'Bug': u'bug'}
2009-10-08 09:56:16,008 Trac[log] DEBUG: [AgiloTicketSystem]:
Returning Normalized Type: story
2009-10-08 09:56:16,008 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Loaded agilo properties: CALCULATED:{u'total_remaining_time':
<agilo.ticket.links.operators.SummingOperator object at 0x8e671cc>,
u'estimated_remaining_time':
<agilo.ticket.links.operators.MultiplicationOperator object at
0x87accac>} ALLOWED:{u'task': <agilo.ticket.links.model.AllowedLink
object at 0x8d2932c>} SORT:{u'task': []} SHOW:{u'task': [u'owner',
u'remaining_time']}
2009-10-08 09:56:16,009 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: summary = fdssdf (<type 'unicode'>)
2009-10-08 09:56:16,010 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,011 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: description = (<type 'str'>)
2009-10-08 09:56:16,019 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,020 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Called insert()...
2009-10-08 09:56:16,021 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,021 Trac[log] DEBUG: [RuleEngine]: Called
validate_rules(<AgiloTicket #0 (u'story')>)
2009-10-08 09:56:16,058 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,058 Trac[log] DEBUG: [SprintAndMilestoneSyncRule]:
Called validate(<AgiloTicket #0 (u'story')>)...
2009-10-08 09:56:16,059 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,060 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: sprint = None (<type 'NoneType'>)
2009-10-08 09:56:16,060 Trac[log] DEBUG: [SprintAndMilestoneSyncRule]:
Synchronizing Sprint(None) and Milestone(None)... ({'status': u'',
'summary': '', 'type': u'incident', 'reporter': ''})
2009-10-08 09:56:16,061 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,061 Trac[log] DEBUG: [ResetOwnerAndResourcesRule]:
Called validate(<AgiloTicket #0 (u'story')>)...
2009-10-08 09:56:16,062 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,062 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,063 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,064 Trac[log] DEBUG: [OwnerIsATeamMemberRule]:
Called validate(<AgiloTicket #0 (u'story')>)...
2009-10-08 09:56:16,064 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,065 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,065 Trac[log] DEBUG:
[CloseTicketWithRemainingTimeZeroRule]: Called validate(<AgiloTicket
#0 (u'story')>)...
2009-10-08 09:56:16,066 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,066 Trac[log] DEBUG:
[CleanLettersFromRemainingTimeRule]: Called validate(<AgiloTicket #0
(u'story')>)...
2009-10-08 09:56:16,067 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,068 Trac[log] DEBUG:
[SetStoryInProgressWhenAtLeastOneTask]: Called validate(<AgiloTicket
#0 (u'story')>)...
2009-10-08 09:56:16,069 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: summary = fdssdf (<type 'unicode'>)
2009-10-08 09:56:16,069 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: reporter = krohrbac (<type 'unicode'>)
2009-10-08 09:56:16,070 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: owner = (<type 'str'>)
2009-10-08 09:56:16,071 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: description = (<type 'str'>)
2009-10-08 09:56:16,071 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,072 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: status = new (<type 'unicode'>)
2009-10-08 09:56:16,072 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: resolution = (<type 'str'>)
2009-10-08 09:56:16,073 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: keywords = (<type 'str'>)
2009-10-08 09:56:16,343 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: sprint = None (<type 'NoneType'>)
2009-10-08 09:56:16,344 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: remaining_time = None (<type 'NoneType'>)
2009-10-08 09:56:16,345 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,346 Trac[log] DEBUG: [AgiloTicketSystem]:
Checking: story with: {u'story': u'User Story', u'task': u'Task',
u'requirement': u'Requirement', u'incident': u'Support Incident',
u'recurring': u'Recurring', u'bug': u'Bug'}, {u'Requirement':
u'requirement', u'Task': u'task', u'Support Incident': u'incident',
u'User Story': u'story', u'Recurring': u'recurring', u'Bug': u'bug'}
2009-10-08 09:56:16,346 Trac[log] DEBUG: [AgiloTicketSystem]:
Returning Normalized Type: story
2009-10-08 09:56:16,347 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,347 Trac[log] DEBUG: [id<149292332> AgiloTicket
(#0)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,416 Trac[log] DEBUG: [<AgiloTicket #68 (u'story')
>]: Requested attribute: summary = fdssdf (<type 'unicode'>)
2009-10-08 09:56:16,427 Trac[log] DEBUG: [<AgiloTicket #68 (u'story')
>]: Requested attribute: reporter = krohrbac (<type 'unicode'>)
2009-10-08 09:56:16,427 Trac[log] DEBUG: [<AgiloTicket #68 (u'story')
>]: Requested attribute: owner = (<type 'str'>)
2009-10-08 09:56:16,428 Trac[log] DEBUG: [<AgiloTicket #68 (u'story')
>]: Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,428 Trac[log] DEBUG: [<AgiloTicket #68 (u'story')
>]: Requested attribute: status = new (<type 'unicode'>)
2009-10-08 09:56:16,429 Trac[log] DEBUG: [<AgiloTicket #68 (u'story')
>]: Requested attribute: resolution = (<type 'str'>)
2009-10-08 09:56:16,429 Trac[log] DEBUG: [<AgiloTicket #68 (u'story')
>]: Requested attribute: keywords = (<type 'str'>)
2009-10-08 09:56:16,429 Trac[log] DEBUG: [<AgiloTicket #68 (u'story')
>]: Requested attribute: reporter = krohrbac (<type 'unicode'>)
2009-10-08 09:56:16,430 Trac[log] DEBUG: [<AgiloTicket #68 (u'story')
>]: Requested attribute: owner = (<type 'str'>)
2009-10-08 09:56:16,430 Trac[log] DEBUG: [<AgiloTicket #68 (u'story')
>]: Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,431 Trac[log] DEBUG: [<AgiloTicket #68 (u'story')
>]: Requested attribute: status = new (<type 'unicode'>)
2009-10-08 09:56:16,431 Trac[log] DEBUG: [<AgiloTicket #68 (u'story')
>]: Requested attribute: resolution = (<type 'str'>)
2009-10-08 09:56:16,431 Trac[log] DEBUG: [<AgiloTicket #68 (u'story')
>]: Requested attribute: keywords = (<type 'str'>)
2009-10-08 09:56:16,432 Trac[log] DEBUG: [<AgiloTicket #68 (u'story')
>]: Requested attribute: summary = fdssdf (<type 'unicode'>)
2009-10-08 09:56:16,433 Trac[session] DEBUG: Retrieving session for ID
'krohrbac'
2009-10-08 09:56:16,572 Trac[main] DEBUG: Dispatching <Request "GET u'/
ticket/68'">
2009-10-08 09:56:16,605 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Initializing with: 68, None, None
2009-10-08 09:56:16,609 Trac[log] DEBUG: [AgiloTicketSystem]:
Checking: story with: {u'story': u'User Story', u'task': u'Task',
u'requirement': u'Requirement', u'incident': u'Support Incident',
u'recurring': u'Recurring', u'bug': u'Bug'}, {u'Requirement':
u'requirement', u'Task': u'task', u'Support Incident': u'incident',
u'User Story': u'story', u'Recurring': u'recurring', u'Bug': u'bug'}
2009-10-08 09:56:16,609 Trac[log] DEBUG: [AgiloTicketSystem]:
Returning Normalized Type: story
2009-10-08 09:56:16,610 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: type = None (<type 'NoneType'>)
2009-10-08 09:56:16,611 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: type = None (<type 'NoneType'>)
2009-10-08 09:56:16,611 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Resetting fields for type(story): []
2009-10-08 09:56:16,611 Trac[log] DEBUG: [AgiloTicketSystem]:
Checking: story with: {u'story': u'User Story', u'task': u'Task',
u'requirement': u'Requirement', u'incident': u'Support Incident',
u'recurring': u'Recurring', u'bug': u'Bug'}, {u'Requirement':
u'requirement', u'Task': u'task', u'Support Incident': u'incident',
u'User Story': u'story', u'Recurring': u'recurring', u'Bug': u'bug'}
2009-10-08 09:56:16,612 Trac[log] DEBUG: [AgiloTicketSystem]:
Returning Normalized Type: story
2009-10-08 09:56:16,612 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Loaded agilo properties: CALCULATED:{u'total_remaining_time':
<agilo.ticket.links.operators.SummingOperator object at 0x8e671cc>,
u'estimated_remaining_time':
<agilo.ticket.links.operators.MultiplicationOperator object at
0x87accac>} ALLOWED:{u'task': <agilo.ticket.links.model.AllowedLink
object at 0x8d2932c>} SORT:{u'task': []} SHOW:{u'task': [u'owner',
u'remaining_time']}
2009-10-08 09:56:16,617 Trac[log] DEBUG: [AgiloTicketSystem]:
Checking: story with: {u'story': u'User Story', u'task': u'Task',
u'requirement': u'Requirement', u'incident': u'Support Incident',
u'recurring': u'Recurring', u'bug': u'Bug'}, {u'Requirement':
u'requirement', u'Task': u'task', u'Support Incident': u'incident',
u'User Story': u'story', u'Recurring': u'recurring', u'Bug': u'bug'}
2009-10-08 09:56:16,618 Trac[log] DEBUG: [AgiloTicketSystem]:
Returning Normalized Type: story
2009-10-08 09:56:16,618 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: type = None (<type 'NoneType'>)
2009-10-08 09:56:16,623 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: sprint = None (<type 'NoneType'>)
2009-10-08 09:56:16,624 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Called _set_team_members() => 'None'
2009-10-08 09:56:16,628 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,629 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: sprint = None (<type 'NoneType'>)
2009-10-08 09:56:16,630 Trac[log] DEBUG: [Environment]: SELECT =>
Executing Query: SELECT description, sprint_end, milestone, name,
start, team FROM agilo_sprint ORDER BY start {}
2009-10-08 09:56:16,634 Trac[log] DEBUG: [Team]: DB Connection: None
2009-10-08 09:56:16,635 Trac[log] DEBUG: [Team]: LOAD => Executing
Query: SELECT description, name FROM agilo_team WHERE name=%(name_old)
s ({'name_old': u'Infrastructure', 'name': u'Infrastructure'})
2009-10-08 09:56:16,637 Trac[log] DEBUG: [Team]: Read from DB: (None,
u'Infrastructure')
2009-10-08 09:56:16,638 Trac[log] DEBUG: [Team]: Read object from the
DB! <Team u'Infrastructure'>
2009-10-08 09:56:16,640 Trac[log] DEBUG: [Sprint]: Returning duration
15 start: 2009-10-01 22:00:00+00:00, end: 2009-10-21 22:00:00+00:00
2009-10-08 09:56:16,643 Trac[log] DEBUG: [Environment]: SELECT =>
Executing Query: SELECT description, name, team, ts_fri, ts_mon,
ts_sat, ts_sun, ts_thu, ts_tue, ts_wed FROM agilo_team_member WHERE
team=%(team)s {'team': u'Infrastructure'}
2009-10-08 09:56:16,729 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: status = new (<type 'unicode'>)
2009-10-08 09:56:16,731 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: status = new (<type 'unicode'>)
2009-10-08 09:56:16,731 Trac[default_workflow] DEBUG:
render_ticket_action_control: action "leave"
2009-10-08 09:56:16,732 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: owner = (<type 'unicode'>)
2009-10-08 09:56:16,733 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: status = new (<type 'unicode'>)
2009-10-08 09:56:16,734 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: status = new (<type 'unicode'>)
2009-10-08 09:56:16,734 Trac[default_workflow] DEBUG:
render_ticket_action_control: action "resolve"
2009-10-08 09:56:16,735 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: owner = (<type 'unicode'>)
2009-10-08 09:56:16,741 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: status = new (<type 'unicode'>)
2009-10-08 09:56:16,742 Trac[default_workflow] DEBUG:
render_ticket_action_control: action "reassign"
2009-10-08 09:56:16,742 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: owner = (<type 'unicode'>)
2009-10-08 09:56:16,744 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: status = new (<type 'unicode'>)
2009-10-08 09:56:16,744 Trac[default_workflow] DEBUG:
render_ticket_action_control: action "accept"
2009-10-08 09:56:16,745 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: owner = (<type 'unicode'>)
2009-10-08 09:56:16,746 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: owner = (<type 'unicode'>)
2009-10-08 09:56:16,751 Trac[log] DEBUG: [id<151099212> AgiloTicket
(#68)] Initializing with: 68, None, None
2009-10-08 09:56:16,756 Trac[log] DEBUG: [AgiloTicketSystem]:
Checking: story with: {u'story': u'User Story', u'task': u'Task',
u'requirement': u'Requirement', u'incident': u'Support Incident',
u'recurring': u'Recurring', u'bug': u'Bug'}, {u'Requirement':
u'requirement', u'Task': u'task', u'Support Incident': u'incident',
u'User Story': u'story', u'Recurring': u'recurring', u'Bug': u'bug'}
2009-10-08 09:56:16,756 Trac[log] DEBUG: [AgiloTicketSystem]:
Returning Normalized Type: story
2009-10-08 09:56:16,757 Trac[log] DEBUG: [id<151099212> AgiloTicket
(#68)] Requested attribute: type = None (<type 'NoneType'>)
2009-10-08 09:56:16,757 Trac[log] DEBUG: [id<151099212> AgiloTicket
(#68)] Requested attribute: type = None (<type 'NoneType'>)
2009-10-08 09:56:16,758 Trac[log] DEBUG: [id<151099212> AgiloTicket
(#68)] Resetting fields for type(story): []
2009-10-08 09:56:16,758 Trac[log] DEBUG: [AgiloTicketSystem]:
Checking: story with: {u'story': u'User Story', u'task': u'Task',
u'requirement': u'Requirement', u'incident': u'Support Incident',
u'recurring': u'Recurring', u'bug': u'Bug'}, {u'Requirement':
u'requirement', u'Task': u'task', u'Support Incident': u'incident',
u'User Story': u'story', u'Recurring': u'recurring', u'Bug': u'bug'}
2009-10-08 09:56:16,759 Trac[log] DEBUG: [AgiloTicketSystem]:
Returning Normalized Type: story
2009-10-08 09:56:16,760 Trac[log] DEBUG: [id<151099212> AgiloTicket
(#68)] Loaded agilo properties: CALCULATED:{u'total_remaining_time':
<agilo.ticket.links.operators.SummingOperator object at 0x8e671cc>,
u'estimated_remaining_time':
<agilo.ticket.links.operators.MultiplicationOperator object at
0x87accac>} ALLOWED:{u'task': <agilo.ticket.links.model.AllowedLink
object at 0x8d2932c>} SORT:{u'task': []} SHOW:{u'task': [u'owner',
u'remaining_time']}
2009-10-08 09:56:16,763 Trac[log] DEBUG: [AgiloTicketSystem]:
Checking: story with: {u'story': u'User Story', u'task': u'Task',
u'requirement': u'Requirement', u'incident': u'Support Incident',
u'recurring': u'Recurring', u'bug': u'Bug'}, {u'Requirement':
u'requirement', u'Task': u'task', u'Support Incident': u'incident',
u'User Story': u'story', u'Recurring': u'recurring', u'Bug': u'bug'}
2009-10-08 09:56:16,765 Trac[log] DEBUG: [AgiloTicketSystem]:
Returning Normalized Type: story
2009-10-08 09:56:16,765 Trac[log] DEBUG: [id<151099212> AgiloTicket
(#68)] Requested attribute: type = None (<type 'NoneType'>)
2009-10-08 09:56:16,771 Trac[log] DEBUG: [id<151099212> AgiloTicket
(#68)] Requested attribute: sprint = None (<type 'NoneType'>)
2009-10-08 09:56:16,771 Trac[log] DEBUG: [id<151099212> AgiloTicket
(#68)] Called _set_team_members() => 'None'
2009-10-08 09:56:16,773 Trac[session] DEBUG: Retrieving session for ID
'krohrbac'
2009-10-08 09:56:16,783 Trac[chrome] DEBUG: Prepare chrome data for
request
2009-10-08 09:56:16,802 Trac[log] DEBUG: [AgiloTicketModule]: $$$
VALUE OF back_to_url:
https://svn.wibas.de/trac/infrastructure/admin/general/logging
$$$
2009-10-08 09:56:16,803 Trac[log] DEBUG: [id<151099212> AgiloTicket
(#68)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:16,806 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'available_types'
2009-10-08 09:56:16,807 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'row_groups'
2009-10-08 09:56:16,807 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'allowed_links'
2009-10-08 09:56:16,808 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'source'
2009-10-08 09:56:16,808 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'ticket_types'
2009-10-08 09:56:16,808 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'target'
2009-10-08 09:56:16,809 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
story, Alias: User Story Permission: AGILO_CREATE_STORY
2009-10-08 09:56:16,809 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
task, Alias: Task Permission: AGILO_CREATE_TASK
2009-10-08 09:56:16,810 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
requirement, Alias: Requirement Permission: AGILO_CREATE_REQUIREMENT
2009-10-08 09:56:16,811 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
incident, Alias: Support Incident Permission: CREATE_INCIDENT
2009-10-08 09:56:16,811 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
recurring, Alias: Recurring Permission: CREATE_RECURRING
2009-10-08 09:56:16,812 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
bug, Alias: Bug Permission: AGILO_CREATE_BUG
2009-10-08 09:56:16,812 Trac[log] DEBUG: [CoreTemplateProvider]:
krohrbac has create permission for types: [u'story', u'task',
u'requirement', u'incident', u'recurring', u'bug']
2009-10-08 09:56:16,813 Trac[log] DEBUG: [Environment]: SELECT =>
Executing Query: SELECT description, sprint_end, milestone, name,
start, team FROM agilo_sprint ORDER BY start {}
2009-10-08 09:56:16,817 Trac[log] DEBUG: [Sprint]: Returning duration
15 start: 2009-10-01 22:00:00+00:00, end: 2009-10-21 22:00:00+00:00
2009-10-08 09:56:16,819 Trac[log] DEBUG: [Environment]: SELECT =>
Executing Query: SELECT description, name, team, ts_fri, ts_mon,
ts_sat, ts_sun, ts_thu, ts_tue, ts_wed FROM agilo_team_member WHERE
team=%(team)s {'team': u'Infrastructure'}
2009-10-08 09:56:16,839 Trac[log] DEBUG: [Environment]: [Backlog]:
Select => SELECT name FROM agilo_backlog ORDER BY name
2009-10-08 09:56:16,841 Trac[log] DEBUG: [Backlog]: SQL Query: SELECT
name, description, ticket_types, sorting_keys, b_type, b_strict FROM
agilo_backlog WHERE name=%s
2009-10-08 09:56:16,843 Trac[log] DEBUG: [Backlog]: SQL Query: SELECT
name, description, ticket_types, sorting_keys, b_type, b_strict FROM
agilo_backlog WHERE name=%s
2009-10-08 09:56:17,098 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: summary = fdssdf (<type 'unicode'>)
2009-10-08 09:56:17,164 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: status = new (<type 'unicode'>)
2009-10-08 09:56:17,165 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:17,165 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:17,165 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: resolution = (<type 'unicode'>)
2009-10-08 09:56:17,171 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: summary = fdssdf (<type 'unicode'>)
2009-10-08 09:56:17,173 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: reporter = krohrbac (<type 'unicode'>)
2009-10-08 09:56:17,175 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: owner = (<type 'unicode'>)
2009-10-08 09:56:17,178 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: keywords = (<type 'unicode'>)
2009-10-08 09:56:17,180 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: rd_points = None (<type 'NoneType'>)
2009-10-08 09:56:17,183 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: sprint = None (<type 'NoneType'>)
2009-10-08 09:56:17,185 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: story_priority = None (<type 'NoneType'>)
2009-10-08 09:56:17,187 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: description = (<type 'unicode'>)
2009-10-08 09:56:17,187 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: description = (<type 'unicode'>)
2009-10-08 09:56:17,188 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: description = (<type 'unicode'>)
2009-10-08 09:56:17,193 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: summary = fdssdf (<type 'unicode'>)
2009-10-08 09:56:17,200 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: summary = fdssdf (<type 'unicode'>)
2009-10-08 09:56:17,201 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: reporter = krohrbac (<type 'unicode'>)
2009-10-08 09:56:17,203 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: description = (<type 'unicode'>)
2009-10-08 09:56:17,204 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:17,235 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:17,237 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: status = new (<type 'unicode'>)
2009-10-08 09:56:17,255 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:17,256 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested calculated value total_remaining_time for type story
2009-10-08 09:56:17,259 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested calculated property: total_remaining_time = None
(<type 'NoneType'>)
2009-10-08 09:56:17,260 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:17,260 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested calculated value estimated_remaining_time for type
story
2009-10-08 09:56:17,261 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: rd_points = None (<type 'NoneType'>)
2009-10-08 09:56:17,261 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested calculated property: estimated_remaining_time = None
(<type 'NoneType'>)
2009-10-08 09:56:17,264 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:17,265 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested calculated value total_remaining_time for type story
2009-10-08 09:56:17,268 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested calculated property: total_remaining_time = None
(<type 'NoneType'>)
2009-10-08 09:56:17,271 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: type = story (<type 'unicode'>)
2009-10-08 09:56:17,271 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested calculated value estimated_remaining_time for type
story
2009-10-08 09:56:17,272 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested attribute: rd_points = None (<type 'NoneType'>)
2009-10-08 09:56:17,272 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Requested calculated property: estimated_remaining_time = None
(<type 'NoneType'>)
2009-10-08 09:56:17,282 Trac[log] DEBUG: [id<151006124> AgiloTicket
(#68)] Sorted outgoing links: []
2009-10-08 09:56:19,081 Trac[main] DEBUG: Dispatching <Request "GET u'/
report'">
2009-10-08 09:56:19,101 Trac[report] DEBUG: Executing report with SQL
"SELECT id AS report, title, 'report' as _realm FROM report ORDER BY
report"
2009-10-08 09:56:19,102 Trac[report] DEBUG: Request args: {}
2009-10-08 09:56:19,110 Trac[chrome] DEBUG: Prepare chrome data for
request
2009-10-08 09:56:19,129 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'fields'
2009-10-08 09:56:19,130 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'changes'
2009-10-08 09:56:19,130 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'available_types'
2009-10-08 09:56:19,134 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'allowed_links'
2009-10-08 09:56:19,134 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'source'
2009-10-08 09:56:19,135 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'ticket_types'
2009-10-08 09:56:19,135 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'target'
2009-10-08 09:56:19,136 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
story, Alias: User Story Permission: AGILO_CREATE_STORY
2009-10-08 09:56:19,136 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
task, Alias: Task Permission: AGILO_CREATE_TASK
2009-10-08 09:56:19,137 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
requirement, Alias: Requirement Permission: AGILO_CREATE_REQUIREMENT
2009-10-08 09:56:19,137 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
incident, Alias: Support Incident Permission: CREATE_INCIDENT
2009-10-08 09:56:19,138 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
recurring, Alias: Recurring Permission: CREATE_RECURRING
2009-10-08 09:56:19,138 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
bug, Alias: Bug Permission: AGILO_CREATE_BUG
2009-10-08 09:56:19,139 Trac[log] DEBUG: [CoreTemplateProvider]:
krohrbac has create permission for types: [u'story', u'task',
u'requirement', u'incident', u'recurring', u'bug']
2009-10-08 09:56:19,140 Trac[session] DEBUG: Retrieving session for ID
'krohrbac'
2009-10-08 09:56:19,636 Trac[main] DEBUG: Dispatching <Request "GET u'/
wiki/TitleIndex'">
2009-10-08 09:56:19,742 Trac[chrome] DEBUG: Prepare chrome data for
request
2009-10-08 09:56:19,800 Trac[api] DEBUG: Updating wiki page index
2009-10-08 09:56:19,833 Trac[log] DEBUG: [id<150664172> AgiloTicket
(#0)] Initializing with: None, None, None
2009-10-08 09:56:19,834 Trac[log] DEBUG: [AgiloTicketSystem]:
Checking: incident with: {u'story': u'User Story', u'task': u'Task',
u'requirement': u'Requirement', u'incident': u'Support Incident',
u'recurring': u'Recurring', u'bug': u'Bug'}, {u'Requirement':
u'requirement', u'Task': u'task', u'Support Incident': u'incident',
u'User Story': u'story', u'Recurring': u'recurring', u'Bug': u'bug'}
2009-10-08 09:56:19,835 Trac[log] DEBUG: [AgiloTicketSystem]:
Returning Normalized Type: incident
2009-10-08 09:56:19,835 Trac[log] DEBUG: [id<150664172> AgiloTicket
(#0)] Requested attribute: type = None (<type 'NoneType'>)
2009-10-08 09:56:19,836 Trac[log] DEBUG: [id<150664172> AgiloTicket
(#0)] Requested attribute: type = None (<type 'NoneType'>)
2009-10-08 09:56:19,836 Trac[log] DEBUG: [id<150664172> AgiloTicket
(#0)] Resetting fields for type(incident): []
2009-10-08 09:56:19,836 Trac[log] DEBUG: [AgiloTicketSystem]:
Checking: incident with: {u'story': u'User Story', u'task': u'Task',
u'requirement': u'Requirement', u'incident': u'Support Incident',
u'recurring': u'Recurring', u'bug': u'Bug'}, {u'Requirement':
u'requirement', u'Task': u'task', u'Support Incident': u'incident',
u'User Story': u'story', u'Recurring': u'recurring', u'Bug': u'bug'}
2009-10-08 09:56:19,836 Trac[log] DEBUG: [AgiloTicketSystem]:
Returning Normalized Type: incident
2009-10-08 09:56:19,851 Trac[log] DEBUG: [id<150664172> AgiloTicket
(#0)] Loaded agilo properties: CALCULATED:{} ALLOWED:{} SORT:{} SHOW:
{}
2009-10-08 09:56:19,852 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'fields'
2009-10-08 09:56:19,853 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'changes'
2009-10-08 09:56:19,853 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'available_types'
2009-10-08 09:56:19,855 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'row_groups'
2009-10-08 09:56:19,855 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'allowed_links'
2009-10-08 09:56:19,857 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'source'
2009-10-08 09:56:19,857 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'ticket_types'
2009-10-08 09:56:19,858 Trac[log] WARNING: [CommandParser] [By Key]:
Warning 'target'
2009-10-08 09:56:19,858 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
story, Alias: User Story Permission: AGILO_CREATE_STORY
2009-10-08 09:56:19,870 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
task, Alias: Task Permission: AGILO_CREATE_TASK
2009-10-08 09:56:19,871 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
requirement, Alias: Requirement Permission: AGILO_CREATE_REQUIREMENT
2009-10-08 09:56:19,872 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
incident, Alias: Support Incident Permission: CREATE_INCIDENT
2009-10-08 09:56:19,872 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
recurring, Alias: Recurring Permission: CREATE_RECURRING
2009-10-08 09:56:19,873 Trac[log] DEBUG: [CoreTemplateProvider]: Type:
bug, Alias: Bug Permission: AGILO_CREATE_BUG
2009-10-08 09:56:19,873 Trac[log] DEBUG: [CoreTemplateProvider]:
krohrbac has create permission for types: [u'story', u'task',
u'requirement', u'incident', u'recurring', u'bug']
2009-10-08 09:56:19,875 Trac[session] DEBUG: Retrieving session for ID
'krohrbac'
2009-10-08 09:56:21,036 Trac[formatter] DEBUG: Executing Wiki macro
TitleIndex by provider <trac.wiki.macros.TitleIndexMacro object at
0x8fca38c>