lucas,
OK. So there is no error at SQL level. The problem is probably a closing
cursor that happens somewhere in the DAL, that is triggered by some
usage/environment peculiar to your application.
Check the following just to be sure:
1) pooling connections. Try to work with pooled connection and without and
see if anything changes.
2) explicit commits, rollbacks.
3) access to DBAPI cursor bypassing the DAL.
4) check if psycopg2 is recent and compiled againsts libpq-9.1
Also what kind of server configuration you have? rocket, apache+wsgi or ...
Does the error happen on a multicore machine and there are concurrent
requests? See the pooling item above..
What we really need is a simplified version of your code that shows the
same error.
mic
2012/9/7 lucas <sjluk...@gmail.com>
> ok, so i turned on debug5 in the postgres.conf file and restarted it.
>> gives lots of entries. i then waited for that type ticket to arise again,
>> it did, and traced to logs to that date/time stamp. the entries are
>> below. i really can't see anything unusual except to say that it is
>> occuring at login. i don't know exactly what kind of error postgres might
>> give, but it looks like it commits and tidies up after the queries just
>> fine. not really seeing any problem there and it only confirms that web2py
>> is bugging during the login. lucas.
>>
> 2012-09-06 19:51:07 EDT: [unknown]LOG: connection received:
> host=127.0.0.1 port=50185
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: postmaster child[27375]:
> starting with (
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: postgres
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: weinstruct
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: )
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: InitPostgres
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: my backend ID is 2
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: StartTransaction
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: name: unnamed; blockState:
> DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: received password packet
> 2012-09-06 19:51:07 EDT: [unknown]LOG: connection authorized:
> user=postgres database=weinstruct
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: CommitTransaction
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: name: unnamed; blockState:
> STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: StartTransactionCommand
> 2012-09-06 19:51:07 EDT: [unknown]STATEMENT: BEGIN
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: StartTransaction
> 2012-09-06 19:51:07 EDT: [unknown]STATEMENT: BEGIN
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: name: unnamed; blockState:
> DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:
> 2012-09-06 19:51:07 EDT: [unknown]STATEMENT: BEGIN
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: ProcessUtility
> 2012-09-06 19:51:07 EDT: [unknown]STATEMENT: BEGIN
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: CommitTransactionCommand
> 2012-09-06 19:51:07 EDT: [unknown]STATEMENT: BEGIN
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: StartTransactionCommand
> 2012-09-06 19:51:07 EDT: [unknown]STATEMENT: SET
> standard_conforming_strings=on;
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: ProcessUtility
> 2012-09-06 19:51:07 EDT: [unknown]STATEMENT: SET
> standard_conforming_strings=on;
> 2012-09-06 19:51:07 EDT: [unknown]DEBUG: CommitTransactionCommand
> 2012-09-06 19:51:07 EDT: [unknown]STATEMENT: SET
> standard_conforming_strings=on;
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: StartTransactionCommand
> 2012-09-06 19:51:08 EDT: [unknown]STATEMENT: SELECT auth_user.id,
> auth_user.prefix_title, auth_user.first_name, auth_user.last_name,
> auth_user.nick_name, auth_user.city_state, auth_user.zip, auth_user.gender,
> auth_user.age, auth_user.birthdate,
> auth_user.name_of_college_or_university, auth_user.school_id,
> auth_user.degree_seeking, auth_user.email, auth_user.password,
> auth_user.registration_key, auth_user.reset_password_key,
> auth_user.registration_id, auth_user.professor,
> auth_user.create_lecture_request, auth_user.create_lecture_agreement,
> auth_user.create_lecture_foldername, auth_user.input_date FROM auth_user
> WHERE (auth_user.email = 'dykab...@yahoo.com');
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: CommitTransactionCommand
> 2012-09-06 19:51:08 EDT: [unknown]STATEMENT: SELECT auth_user.id,
> auth_user.prefix_title, auth_user.first_name, auth_user.last_name,
> auth_user.nick_name, auth_user.city_state, auth_user.zip, auth_user.gender,
> auth_user.age, auth_user.birthdate,
> auth_user.name_of_college_or_university, auth_user.school_id,
> auth_user.degree_seeking, auth_user.email, auth_user.password,
> auth_user.registration_key, auth_user.reset_password_key,
> auth_user.registration_id, auth_user.professor,
> auth_user.create_lecture_request, auth_user.create_lecture_agreement,
> auth_user.create_lecture_foldername, auth_user.input_date FROM auth_user
> WHERE (auth_user.email = 'dykab...@yahoo.com');
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: StartTransactionCommand
> 2012-09-06 19:51:08 EDT: [unknown]STATEMENT: SELECT auth_membership.id,
> auth_membership.user_id, auth_membership.group_id FROM auth_membership
> WHERE (auth_membership.user_id = 20);
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: CommitTransactionCommand
> 2012-09-06 19:51:08 EDT: [unknown]STATEMENT: SELECT auth_membership.id,
> auth_membership.user_id, auth_membership.group_id FROM auth_membership
> WHERE (auth_membership.user_id = 20);
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: StartTransactionCommand
> 2012-09-06 19:51:08 EDT: [unknown]STATEMENT: SELECT auth_group.id,
> auth_group.role, auth_group.description FROM auth_group WHERE (
> auth_group.id = 3) ORDER BY auth_group.id LIMIT 1 OFFSET 0;
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: CommitTransactionCommand
> 2012-09-06 19:51:08 EDT: [unknown]STATEMENT: SELECT auth_group.id,
> auth_group.role, auth_group.description FROM auth_group WHERE (
> auth_group.id = 3) ORDER BY auth_group.id LIMIT 1 OFFSET 0;
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: StartTransactionCommand
> 2012-09-06 19:51:08 EDT: [unknown]STATEMENT: INSERT INTO
> auth_event(origin,time_stamp,client_ip,user_id,description) VALUES
> ('auth','2012-09-06 19:51:07','108.75.165.231',20,'User 20 Logged-in');
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: ProcessQuery
> 2012-09-06 19:51:08 EDT: [unknown]STATEMENT: INSERT INTO
> auth_event(origin,time_stamp,client_ip,user_id,description) VALUES
> ('auth','2012-09-06 19:51:07','108.75.165.231',20,'User 20 Logged-in');
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: CommitTransactionCommand
> 2012-09-06 19:51:08 EDT: [unknown]STATEMENT: INSERT INTO
> auth_event(origin,time_stamp,client_ip,user_id,description) VALUES
> ('auth','2012-09-06 19:51:07','108.75.165.231',20,'User 20 Logged-in');
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: StartTransactionCommand
> 2012-09-06 19:51:08 EDT: [unknown]STATEMENT: select
> currval('auth_event_id_Seq')
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: CommitTransactionCommand
> 2012-09-06 19:51:08 EDT: [unknown]STATEMENT: select
> currval('auth_event_id_Seq')
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: StartTransactionCommand
> 2012-09-06 19:51:08 EDT: [unknown]STATEMENT: COMMIT
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: ProcessUtility
> 2012-09-06 19:51:08 EDT: [unknown]STATEMENT: COMMIT
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: CommitTransactionCommand
> 2012-09-06 19:51:08 EDT: [unknown]STATEMENT: COMMIT
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: CommitTransaction
> 2012-09-06 19:51:08 EDT: [unknown]STATEMENT: COMMIT
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: name: unnamed;
> blockState: END; state: INPROGR, xid/subid/cid: 5425/1/2,
> nestlvl: 1, children:
> 2012-09-06 19:51:08 EDT: [unknown]STATEMENT: COMMIT
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: shmem_exit(0): 7 callbacks to
> make
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: proc_exit(0): 4 callbacks to make
> 2012-09-06 19:51:08 EDT: [unknown]LOG: disconnection: session time:
> 0:00:00.624 user=postgres database=weinstruct host=127.0.0.1 port=50185
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: exit(0)
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: shmem_exit(-1): 0 callbacks to
> make
> 2012-09-06 19:51:08 EDT: [unknown]DEBUG: proc_exit(-1): 0 callbacks to
> make
>
> --
>
>
>
>
--
