Output of webserver: 2013-01-04 23:27:28,634 INFO open database 'rounduptest' 2013-01-04 23:27:28,640 INFO commit 2013-01-04 23:27:28,642 INFO commit 2013-01-04 23:27:28,642 DEBUG SQL 'select id from _user where _username=%s and __retired__=%s' ('demo', 0) 2013-01-04 23:27:28,643 DEBUG SQL 'select count(*) from _issue where id=%s' ('1',) 2013-01-04 23:27:28,644 DEBUG SQL 'select _activity,_actor,_address,_alternate_addresses,_creation,_creator,_organisation,_password,_phone,_realname,_roles,_timezone,_username from _user where id=%s' ('3',) 2013-01-04 23:27:28,645 DEBUG SQL 'select linkid from user_queries where nodeid=%s' ('3',) 2013-01-04 23:27:28,646 DEBUG SQL 'select _activity,_actor,_assignedto,_creation,_creator,_priority,_status,_title,_tx_Source from _issue where id=%s' ('1',) 2013-01-04 23:27:28,651 DEBUG SQL 'select id from _user where _username=%s and __retired__=%s' ('demo', 0) 2013-01-04 23:27:28,652 DEBUG SQL 'select linkid from issue_keyword where nodeid=%s' ('1',) 2013-01-04 23:27:28,653 DEBUG SQL 'select linkid from issue_nosy where nodeid=%s' ('1',) 2013-01-04 23:27:28,653 DEBUG SQL 'select linkid from issue_superseder where nodeid=%s' ('1',) call on create msg: tx_SourceCheckAudit(None) pre db.tx_Source: web, nonce: b12d7e44844555c99c2f tx_SourceCheckAudit(None) post db.tx_Source: web, nonce b12d7e44844555c99c2f 2013-01-04 23:27:38,665 DEBUG SQL "select nextval('_msg_ids') from dual" None 2013-01-04 23:27:38,667 DEBUG addnode msg6 {'files': [], 'inreplyto': None, 'tx_Source': 'arrived via: web b12d7e44844555c99c2f', 'recipients': [], 'author': '3', 'summary': 'ABC', 'content': None, 'messageid': None, 'date': , 'type': None} 2013-01-04 23:27:38,668 DEBUG SQL 'insert into _msg (_activity,_actor,_author,_content,_creation,_creator,_date,_inreplyto,_messageid,_summary,_tx_Source,_type,id) values (%s,%s,%s,%s,%s,%s,%s,%s,%s,%s,%s,%s,%s)' ('2013-01-04 23:27:38.668', 3, 3, None, '2013-01-04 23:27:38.668', 3, '2013-01-04 23:27:28.654', None, None, 'ABC', 'arrived via: web b12d7e44844555c99c2f', None, '6') 2013-01-04 23:27:38,669 DEBUG addjournal msg6 3 create {} 2013-01-04 23:27:38,670 DEBUG SQL 'insert into msg__journal (nodeid,date,tag,action,params) values (%s,%s,%s,%s,%s)' ('6', '2013-01-04 23:27:38.670', '3', 'create', '{}') 2013-01-04 23:27:38,683 DEBUG SQL 'select linkid from issue_messages where nodeid=%s' ('1',) 2013-01-04 23:27:38,684 DEBUG SQL 'select id from _status where _name=%s and __retired__=%s' ('chatting', 0) 2013-01-04 23:27:38,685 DEBUG SQL 'select id from _status where _name=%s and __retired__=%s' ('unread', 0) 2013-01-04 23:27:38,685 DEBUG SQL 'select id from _status where _name=%s and __retired__=%s' ('resolved', 0) 2013-01-04 23:27:38,685 DEBUG SQL 'select id from _status where _name=%s and __retired__=%s' ('done-cbb', 0) tx_SourceCheckAudit(1) pre db.tx_Source: web, nonce: 0027def0847f11360c3c tx_SourceCheckAudit(1) post db.tx_Source: web, nonce 0027def0847f11360c3c 2013-01-04 23:27:48,693 DEBUG SQL 'select linkid from issue_files where nodeid=%s' ('1',) 2013-01-04 23:27:48,694 DEBUG SQL 'select __retired__ from _issue where id=%s' ('1',) 2013-01-04 23:27:48,694 DEBUG SQL 'select count(*) from _msg where id=%s' ('6',) 2013-01-04 23:27:48,695 DEBUG addjournal msg6 3 link ('issue', '1', 'messages') 2013-01-04 23:27:48,695 DEBUG SQL 'insert into msg__journal (nodeid,date,tag,action,params) values (%s,%s,%s,%s,%s)' ('6', '2013-01-04 23:27:48.696', '3', 'link', "('issue', '1', 'messages')") 2013-01-04 23:27:48,696 DEBUG setnode issue1 {'messages': ['1', '6'], 'tx_Source': 'arrived via: web 0027def0847f11360c3c', 'actor': '3', 'activity': } 2013-01-04 23:27:48,696 DEBUG SQL 'update _issue set _activity=%s,_actor=%s,_tx_Source=%s where id=%s' ('2013-01-04 23:27:48.696', 3, 'arrived via: web 0027def0847f11360c3c', 1) 2013-01-04 23:27:57,705 DEBUG SQL 'insert into issue_messages (nodeid, linkid) values (%s,%s)' (1, 6) where does the "web c05" come from? 2013-01-04 23:27:57,707 DEBUG addjournal issue1 3 set {'messages': (('+', ['6']),), 'tx_Source': 'arrived via: web c05d48aea9f81a9ae254'} 2013-01-04 23:27:57,707 DEBUG SQL 'insert into issue__journal (nodeid,date,tag,action,params) values (%s,%s,%s,%s,%s)' ('1', '2013-01-04 23:27:57.707', '3', 'set', "{'messages': (('+', ['6']),), 'tx_Source': 'arrived via: web c05d48aea9f81a9ae254'}") 2013-01-04 23:27:57,709 DEBUG SQL 'select _activity,_actor,_assignedto,_creation,_creator,_priority,_status,_title,_tx_Source from _issue where id=%s' ('1',) tx_SourceCheckReact(1) pre db.tx_Source: web, recorded: arrived via: web 0027def0847f11360c3c tx_SourceCheckReact(1) post db.tx_Source: web, recorded: arrived via: web 0027def0847f11360c3c 2013-01-04 23:28:07,714 INFO commit 127.0.0.1 - - [04/Jan/2013 23:28:07] "POST /demo/issue1 HTTP/1.1" 302 - 2013-01-04 23:28:07,718 INFO close 2013-01-04 23:28:07,737 INFO open database 'rounduptest' 2013-01-04 23:28:07,742 INFO commit 2013-01-04 23:28:07,744 INFO commit 2013-01-04 23:28:07,745 DEBUG SQL 'select id from _user where _username=%s and __retired__=%s' ('demo', 0) 2013-01-04 23:28:07,745 DEBUG SQL 'select count(*) from _issue where id=%s' ('1',) 2013-01-04 23:28:07,746 DEBUG SQL 'select _activity,_actor,_address,_alternate_addresses,_creation,_creator,_organisation,_password,_phone,_realname,_roles,_timezone,_username from _user where id=%s' ('3',) 2013-01-04 23:28:07,746 DEBUG SQL 'select linkid from user_queries where nodeid=%s' ('3',) 2013-01-04 23:28:07,747 DEBUG SQL 'select _activity,_actor,_assignedto,_creation,_creator,_priority,_status,_title,_tx_Source from _issue where id=%s' ('1',) 2013-01-04 23:28:07,748 DEBUG SQL 'select id from _keyword where __retired__=%s' (0,) 2013-01-04 23:28:07,749 DEBUG SQL 'select _activity,_actor,_creation,_creator,_name from _keyword where id=%s' ('4',) 2013-01-04 23:28:07,749 DEBUG SQL 'select _activity,_actor,_creation,_creator,_name from _keyword where id=%s' ('2',) 2013-01-04 23:28:07,749 DEBUG SQL 'select _activity,_actor,_creation,_creator,_name from _keyword where id=%s' ('1',) 2013-01-04 23:28:07,750 DEBUG SQL 'select _activity,_actor,_creation,_creator,_name from _keyword where id=%s' ('3',) 2013-01-04 23:28:07,753 DEBUG SQL 'select _priority.id,_priority._order,(_priority._order is not NULL) from _priority where _priority.__retired__=0 order by (_priority._order is not NULL),_priority._order,_priority.id' () 2013-01-04 23:28:07,754 DEBUG SQL 'select _activity,_actor,_creation,_creator,_name,_order from _priority where id=%s' ('1',) 2013-01-04 23:28:07,755 DEBUG SQL 'select _activity,_actor,_creation,_creator,_name,_order from _priority where id=%s' ('2',) 2013-01-04 23:28:07,757 DEBUG SQL 'select _activity,_actor,_creation,_creator,_name,_order from _priority where id=%s' ('3',) 2013-01-04 23:28:07,758 DEBUG SQL 'select _activity,_actor,_creation,_creator,_name,_order from _priority where id=%s' ('4',) 2013-01-04 23:28:07,759 DEBUG SQL 'select _activity,_actor,_creation,_creator,_name,_order from _priority where id=%s' ('5',) 2013-01-04 23:28:07,761 DEBUG SQL 'select _status.id,_status._order,(_status._order is not NULL) from _status where _status.__retired__=0 order by (_status._order is not NULL),_status._order,_status.id' () 2013-01-04 23:28:07,761 DEBUG SQL 'select _activity,_actor,_creation,_creator,_name,_order from _status where id=%s' ('1',) 2013-01-04 23:28:07,762 DEBUG SQL 'select _activity,_actor,_creation,_creator,_name,_order from _status where id=%s' ('2',) 2013-01-04 23:28:07,762 DEBUG SQL 'select _activity,_actor,_creation,_creator,_name,_order from _status where id=%s' ('3',) 2013-01-04 23:28:07,762 DEBUG SQL 'select _activity,_actor,_creation,_creator,_name,_order from _status where id=%s' ('4',) 2013-01-04 23:28:07,763 DEBUG SQL 'select _activity,_actor,_creation,_creator,_name,_order from _status where id=%s' ('5',) 2013-01-04 23:28:07,763 DEBUG SQL 'select _activity,_actor,_creation,_creator,_name,_order from _status where id=%s' ('6',) 2013-01-04 23:28:07,763 DEBUG SQL 'select _activity,_actor,_creation,_creator,_name,_order from _status where id=%s' ('7',) 2013-01-04 23:28:07,764 DEBUG SQL 'select _activity,_actor,_creation,_creator,_name,_order from _status where id=%s' ('8',) 2013-01-04 23:28:07,764 DEBUG SQL 'select linkid from issue_superseder where nodeid=%s' ('1',) 2013-01-04 23:28:07,766 DEBUG SQL 'select linkid from issue_nosy where nodeid=%s' ('1',) 2013-01-04 23:28:07,767 DEBUG SQL 'select _user.id,lower(_user._username),(lower(_user._username) is not NULL) from _user where _user.__retired__=0 order by (lower(_user._username) is not NULL),lower(_user._username),_user.id' () 2013-01-04 23:28:07,769 DEBUG SQL 'select _activity,_actor,_address,_alternate_addresses,_creation,_creator,_organisation,_password,_phone,_realname,_roles,_timezone,_username from _user where id=%s' ('1',) 2013-01-04 23:28:07,769 DEBUG SQL 'select _activity,_actor,_address,_alternate_addresses,_creation,_creator,_organisation,_password,_phone,_realname,_roles,_timezone,_username from _user where id=%s' ('2',) 2013-01-04 23:28:07,770 DEBUG SQL 'select _activity,_actor,_address,_alternate_addresses,_creation,_creator,_organisation,_password,_phone,_realname,_roles,_timezone,_username from _user where id=%s' ('4',) 2013-01-04 23:28:07,771 DEBUG SQL 'select _activity,_actor,_address,_alternate_addresses,_creation,_creator,_organisation,_password,_phone,_realname,_roles,_timezone,_username from _user where id=%s' ('5',) 2013-01-04 23:28:07,771 DEBUG SQL 'select _activity,_actor,_address,_alternate_addresses,_creation,_creator,_organisation,_password,_phone,_realname,_roles,_timezone,_username from _user where id=%s' ('6',) 2013-01-04 23:28:07,772 DEBUG SQL 'select _activity,_actor,_address,_alternate_addresses,_creation,_creator,_organisation,_password,_phone,_realname,_roles,_timezone,_username from _user where id=%s' ('7',) 2013-01-04 23:28:07,772 DEBUG SQL 'select linkid from issue_keyword where nodeid=%s' ('1',) 2013-01-04 23:28:07,773 DEBUG SQL 'select id from _user where _username=%s and __retired__=%s' ('demo', 0) 2013-01-04 23:28:07,775 DEBUG SQL 'select linkid from issue_files where nodeid=%s' ('1',) 2013-01-04 23:28:07,775 DEBUG SQL 'select linkid from issue_messages where nodeid=%s' ('1',) 2013-01-04 23:28:07,775 DEBUG SQL 'select _activity,_actor,_author,_content,_creation,_creator,_date,_inreplyto,_messageid,_summary,_tx_Source,_type from _msg where id=%s' ('5',) 2013-01-04 23:28:07,776 DEBUG SQL 'select _activity,_actor,_author,_content,_creation,_creator,_date,_inreplyto,_messageid,_summary,_tx_Source,_type from _msg where id=%s' ('1',) 2013-01-04 23:28:07,776 DEBUG SQL 'select _activity,_actor,_author,_content,_creation,_creator,_date,_inreplyto,_messageid,_summary,_tx_Source,_type from _msg where id=%s' ('6',) 2013-01-04 23:28:07,781 DEBUG SQL 'select nodeid,date,tag,action,params from issue__journal where nodeid=%s order by date' ('1',) 127.0.0.1 - - [04/Jan/2013 23:28:07] "GET /demo/issue1?@ok_message=msg%206%20created%0Aissue%201%20messages%2C%20tx_Source%20edited%20ok&@template=item HTTP/1.1" 200 - 2013-01-04 23:28:07,784 INFO close Output of email command: SENDMAILDEBUG=1 PYTHONPATH="$PWD" python roundup/scripts/roundup_mailgw.py demo < email_1 2013-01-04 23:27:27,617 INFO Handling message 2013-01-04 23:27:27,621 INFO open database 'rounduptest' 2013-01-04 23:27:27,634 INFO commit 2013-01-04 23:27:27,636 DEBUG SQL 'select count(*) from _issue where id=%s' ('1',) 2013-01-04 23:27:27,636 DEBUG SQL 'select id from _user where _username=%s and __retired__=%s' ('anonymous', 0) 2013-01-04 23:27:27,637 DEBUG SQL 'select _activity,_actor,_address,_alternate_addresses,_creation,_creator,_organisation,_password,_phone,_realname,_roles,_timezone,_username from _user where id=%s' ('2',) 2013-01-04 23:27:27,637 DEBUG SQL 'select linkid from user_queries where nodeid=%s' ('2',) 2013-01-04 23:27:27,638 DEBUG SQL 'select id from _user where lower(_address)=%s and __retired__=%s' ('user4', 0) 2013-01-04 23:27:27,638 DEBUG SQL 'select _activity,_actor,_address,_alternate_addresses,_creation,_creator,_organisation,_password,_phone,_realname,_roles,_timezone,_username from _user where id=%s' ('4',) 2013-01-04 23:27:27,639 DEBUG SQL 'select linkid from user_queries where nodeid=%s' ('4',) 2013-01-04 23:27:27,639 INFO commit 2013-01-04 23:27:27,639 DEBUG SQL 'select _activity,_actor,_address,_alternate_addresses,_creation,_creator,_organisation,_password,_phone,_realname,_roles,_timezone,_username from _user where id=%s' ('4',) 2013-01-04 23:27:27,640 DEBUG SQL 'select id from _user where _username=%s and __retired__=%s' ('anonymous', 0) 2013-01-04 23:27:27,640 DEBUG SQL 'select _activity,_actor,_address,_alternate_addresses,_creation,_creator,_organisation,_password,_phone,_realname,_roles,_timezone,_username from _user where id=%s' ('2',) 2013-01-04 23:27:27,641 DEBUG SQL 'select linkid from user_queries where nodeid=%s' ('2',) 2013-01-04 23:27:27,641 DEBUG SQL 'select _activity,_actor,_assignedto,_creation,_creator,_priority,_status,_title,_tx_Source from _issue where id=%s' ('1',) 2013-01-04 23:27:27,641 DEBUG SQL 'select linkid from issue_keyword where nodeid=%s' ('1',) 2013-01-04 23:27:27,642 DEBUG SQL 'select id from _keyword where _name=%s and __retired__=%s' ('keyword1', 0) 2013-01-04 23:27:27,642 DEBUG SQL 'select linkid from user_queries where nodeid=%s' ('4',) call on create msg: tx_SourceCheckAudit(None) pre db.tx_Source: email, nonce: 84b36c36eda460e8797a tx_SourceCheckAudit(None) post db.tx_Source: email, nonce 84b36c36eda460e8797a 2013-01-04 23:27:37,653 DEBUG SQL "select nextval('_msg_ids') from dual" None 2013-01-04 23:27:37,655 DEBUG addnode msg5 {'files': [], 'inreplyto': '', 'tx_Source': 'arrived via: email 84b36c36eda460e8797a', 'recipients': [], 'author': '4', 'summary': 'Something within the rainbow.', 'content': None, 'messageid': '<1357342047.64.0.260792016801.issue1@localhost>', 'date': , 'type': None} 2013-01-04 23:27:37,655 DEBUG SQL 'select id from _user where _username=%s and __retired__=%s' ('user4', 0) 2013-01-04 23:27:37,656 DEBUG SQL 'insert into _msg (_activity,_actor,_author,_content,_creation,_creator,_date,_inreplyto,_messageid,_summary,_tx_Source,_type,id) values (%s,%s,%s,%s,%s,%s,%s,%s,%s,%s,%s,%s,%s)' ('2013-01-04 23:27:37.656', 4, 4, None, '2013-01-04 23:27:37.656', 4, '2013-01-04 23:27:27.643', '', '<1357342047.64.0.260792016801.issue1@localhost>', 'Something within the rainbow.', 'arrived via: email 84b36c36eda460e8797a', None, '5') 2013-01-04 23:27:37,658 DEBUG addjournal msg5 4 create {} 2013-01-04 23:27:37,658 DEBUG SQL 'insert into msg__journal (nodeid,date,tag,action,params) values (%s,%s,%s,%s,%s)' ('5', '2013-01-04 23:27:37.658', '4', 'create', '{}') 2013-01-04 23:27:37,673 DEBUG SQL 'select linkid from issue_messages where nodeid=%s' ('1',) 2013-01-04 23:27:37,673 DEBUG SQL 'select id from _status where _name=%s and __retired__=%s' ('chatting', 0) 2013-01-04 23:27:37,674 DEBUG SQL 'select id from _status where _name=%s and __retired__=%s' ('unread', 0) 2013-01-04 23:27:37,675 DEBUG SQL 'select id from _status where _name=%s and __retired__=%s' ('resolved', 0) 2013-01-04 23:27:37,675 DEBUG SQL 'select id from _status where _name=%s and __retired__=%s' ('done-cbb', 0) tx_SourceCheckAudit(1) pre db.tx_Source: email, nonce: 96f31e4fd457ec6de893 tx_SourceCheckAudit(1) post db.tx_Source: email, nonce 96f31e4fd457ec6de893 2013-01-04 23:27:47,686 DEBUG SQL 'select linkid from issue_files where nodeid=%s' ('1',) 2013-01-04 23:27:47,686 DEBUG SQL 'select linkid from issue_nosy where nodeid=%s' ('1',) 2013-01-04 23:27:47,687 DEBUG SQL 'select linkid from issue_superseder where nodeid=%s' ('1',) 2013-01-04 23:27:47,688 DEBUG SQL 'select __retired__ from _issue where id=%s' ('1',) 2013-01-04 23:27:47,688 DEBUG SQL 'select count(*) from _msg where id=%s' ('5',) 2013-01-04 23:27:47,689 DEBUG addjournal msg5 4 link ('issue', '1', 'messages') 2013-01-04 23:27:47,689 DEBUG SQL 'insert into msg__journal (nodeid,date,tag,action,params) values (%s,%s,%s,%s,%s)' ('5', '2013-01-04 23:27:47.690', '4', 'link', "('issue', '1', 'messages')") 2013-01-04 23:27:47,690 DEBUG SQL 'select count(*) from _keyword where id=%s' ('1',) 2013-01-04 23:27:47,690 DEBUG addjournal keyword1 4 link ('issue', '1', 'keyword') 2013-01-04 23:27:47,690 DEBUG SQL 'insert into keyword__journal (nodeid,date,tag,action,params) values (%s,%s,%s,%s,%s)' ('1', '2013-01-04 23:27:47.691', '4', 'link', "('issue', '1', 'keyword')") 2013-01-04 23:27:47,691 DEBUG setnode issue1 {'actor': '4', 'messages': ['1', '5'], 'tx_Source': 'arrived via: email 96f31e4fd457ec6de893', 'keyword': ['1'], 'activity': } 2013-01-04 23:27:47,691 DEBUG SQL 'update _issue set _activity=%s,_actor=%s,_tx_Source=%s where id=%s' ('2013-01-04 23:27:47.691', 4, 'arrived via: email 96f31e4fd457ec6de893', 1) 2013-01-04 23:27:47,692 DEBUG SQL 'insert into issue_messages (nodeid, linkid) values (%s,%s)' (1, 5) 2013-01-04 23:27:47,692 DEBUG SQL 'insert into issue_keyword (nodeid, linkid) values (%s,%s)' (1, 1) uups, writing "web", should be "email" in the next line, I guess? function is called from ./roundup/backends/rdbms_common.py line 1938: self.db.addjournal(self.classname, nodeid, ''"set", journalvalues) 2013-01-04 23:27:47,692 DEBUG addjournal issue1 4 set {'messages': (('+', ['5']),), 'tx_Source': 'arrived via: web c05d48aea9f81a9ae254', 'keyword': (('+', ['1']),)} 2013-01-04 23:27:47,692 DEBUG SQL 'insert into issue__journal (nodeid,date,tag,action,params) values (%s,%s,%s,%s,%s)' ('1', '2013-01-04 23:27:47.693', '4', 'set', "{'messages': (('+', ['5']),), 'tx_Source': 'arrived via: web c05d48aea9f81a9ae254', 'keyword': (('+', ['1']),)}") 2013-01-04 23:27:47,693 DEBUG SQL 'select _activity,_actor,_assignedto,_creation,_creator,_priority,_status,_title,_tx_Source from _issue where id=%s' ('1',) tx_SourceCheckReact(1) pre db.tx_Source: email, recorded: arrived via: email 96f31e4fd457ec6de893 tx_SourceCheckReact(1) post db.tx_Source: email, recorded: arrived via: email 96f31e4fd457ec6de893 2013-01-04 23:27:57,703 INFO commit 2013-01-04 23:27:57,706 INFO close