Re: Quite strange crash

From: Denis Perchine <dyp(at)perchine(dot)com>
To: Tom Lane <tgl(at)sss(dot)pgh(dot)pa(dot)us>
Cc: pgsql-hackers(at)postgresql(dot)org
Subject: Re: Quite strange crash
Date: 2001-01-08 16:13:04
Message-ID: 01010822130406.00613@dyp.perchine.com
Views: Raw Message | Whole Thread | Download mbox | Resend email
Thread:
Lists: pgsql-hackers

> >>>> FATAL: s_lock(401f7435) at bufmgr.c:2350, stuck spinlock. Aborting.
> >>
> >> Were there any errors before that?
> >
> > No... Just clean log (I redirect log from stderr/out t file, and all
> > other to syslog).
>
> The error messages would be in the syslog then, not in stderr.

Hmmm... The only strange errors I see are:

Jan 7 04:22:14 mx postgres[679]: query: insert into statistic (date,
visit_count, variant_id) values (now(), 1, 2)
Jan 7 04:22:14 mx postgres[631]: query: insert into statistic (date,
visit_count, variant_id) values (now(), 1, 2)
Jan 7 04:22:14 mx postgres[700]: query: insert into statistic (date,
visit_count, variant_id) values (now(), 1, 2)
Jan 7 04:22:14 mx postgres[665]: query: insert into statistic (date,
visit_count, variant_id) values (now(), 1, 2)
Jan 7 04:22:14 mx postgres[633]: query: insert into statistic (date,
visit_count, variant_id) values (now(), 1, 2)
Jan 7 04:22:14 mx postgres[629]: query: insert into statistic (date,
visit_count, variant_id) values (now(), 1, 2)
Jan 7 04:22:14 mx postgres[736]: query: commit
Jan 7 04:22:14 mx postgres[736]: ProcessUtility: commit
Jan 7 04:22:14 mx postgres[700]: ERROR: Cannot insert a duplicate key into
unique index statistic_date_vid_key
Jan 7 04:22:14 mx postgres[700]: query: update users set
rcpt_ip='213.75.35.129',rcptdate=now() where id=1428067
Jan 7 04:22:14 mx postgres[700]: NOTICE: current transaction is aborted,
queries ignored until end of transaction block
Jan 7 04:22:14 mx postgres[679]: query: commit
Jan 7 04:22:14 mx postgres[679]: ProcessUtility: commit
Jan 7 04:22:14 mx postgres[679]: query: update users set
rcpt_ip='213.75.55.185',rcptdate=now() where id=1430836
Jan 7 04:22:14 mx postgres[665]: ERROR: Cannot insert a duplicate key into
unique index statistic_date_vid_key
Jan 7 04:22:14 mx postgres[665]: query: update users set
rcpt_ip='202.156.121.139',rcptdate=now() where id=1271397
Jan 7 04:22:14 mx postgres[665]: NOTICE: current transaction is aborted,
queries ignored until end of transaction block
Jan 7 04:22:14 mx postgres[631]: ERROR: Cannot insert a duplicate key into
unique index statistic_date_vid_key
Jan 7 04:22:14 mx postgres[631]: query: update users set
rcpt_ip='24.20.53.63',rcptdate=now() where id=1451254
Jan 7 04:22:14 mx postgres[631]: NOTICE: current transaction is aborted,
queries ignored until end of transaction block
Jan 7 04:22:14 mx postgres[633]: ERROR: Cannot insert a duplicate key into
unique index statistic_date_vid_key
Jan 7 04:22:14 mx postgres[633]: query: update users set
rcpt_ip='213.116.168.173',rcptdate=now() where id=1378049
Jan 7 04:22:14 mx postgres[633]: NOTICE: current transaction is aborted,
queries ignored until end of transaction block
Jan 7 04:22:14 mx postgres[630]: query: select id,msg,next from alert
Jan 7 04:22:14 mx postgres[630]: query: select email,type from email where
variant_id=2
Jan 7 04:22:14 mx postgres[630]: query:
select * from users where senderdate > now()-'10days'::interval AND
variant_id=2 AND crypt='21AN6KRffJdFRFc511'

Jan 7 04:22:14 mx postgres[629]: ERROR: Cannot insert a duplicate key into
unique index statistic_date_vid_key
Jan 7 04:22:14 mx postgres[629]: query: update users set
rcpt_ip='213.42.45.81',rcptdate=now() where id=1441046
Jan 7 04:22:14 mx postgres[629]: NOTICE: current transaction is aborted,
queries ignored until end of transaction block
Jan 7 04:22:15 mx postgres[711]: query: select message_id from pop3 where
server_id = 17746
Jan 7 04:22:15 mx postgres[711]: ERROR: Relation 'pop3' does not exist

They popped up 4 minutes before. And the most interesting is that relation
pop3 does exist!

> > And the last query was:
> > Jan 7 04:27:53 mx postgres[1008]: query: select message_id from pop3
> > where server_id = 22615
>
> How about the prior queries of other processes?

I do not want to flood maillist (it will be too much of info). I can send you
complete log file from Jan 7. It is 128Mb uncompressed. With gz it is 8Mb.
Maybe it will be smaller with bz2.

> Keep in mind that the
> spinlock could have been left locked by any backend, not only the one
> that complained about it.

Actually you can have a look on the logs yourself. Remember I gave you a
password from postgres user. This is the same postgres. Logs are in
/var/log/postgres. You will need postgres.log.1.gz.

--
Sincerely Yours,
Denis Perchine

----------------------------------
E-Mail: dyp(at)perchine(dot)com
HomePage: http://www.perchine.com/dyp/
FidoNet: 2:5000/120.5
----------------------------------

In response to

Responses

Browse pgsql-hackers by date

  From Date Subject
Next Message Ross J. Reedstrom 2001-01-08 16:56:58 Re: bootstrap tables
Previous Message The Hermit Hacker 2001-01-08 15:55:10 Re: heap_update is broken in current sources