Thread: Errors in logs

Errors in logs

From
Martijn van Oosterhout
Date:
I'm getting these errors in my logs. they don't seem bad but I like to be
sure:

010708.02:33:03.966  [2369] SIInsertDataEntry: table is 70% full, signaling postmaster
010708.02:33:03.966 [27400] pmdie 12
010708.02:33:03.991 [27400] SignalChildren: sending signal 12 to process 2369
010708.02:33:03.991 [27400] SignalChildren: sending signal 12 to process 24757
010708.02:33:03.991 [27400] SignalChildren: sending signal 12 to process 9657
010708.02:33:04.420  [9657] NOTICE:  InvalidateSharedInvalid: cache state reset
010708.04:00:03.924  [2535] NOTICE:  RegisterSharedInvalid: SI buffer overflow
010708.04:00:03.924  [2535] NOTICE:  InvalidateSharedInvalid: cache state reset
010710.04:09:26.357 [18323] NOTICE:  RegisterSharedInvalid: SI buffer overflow
010710.04:09:26.357 [18323] NOTICE:  InvalidateSharedInvalid: cache state reset
010711.02:45:25.467 [25307] SIInsertDataEntry: table is 70% full, signaling post master
010711.02:45:25.467 [27400] pmdie 12
010711.02:45:25.523 [27400] SignalChildren: sending signal 12 to process 25307
010711.02:45:25.523 [27400] SignalChildren: sending signal 12 to process 22906
010711.02:45:25.523 [27400] SignalChildren: sending signal 12 to process 24757
010711.04:03:48.358 [25494] NOTICE:  RegisterSharedInvalid: SI buffer overflow
010711.04:03:48.358 [25494] NOTICE:  InvalidateSharedInvalid: cache state reset

Seems to usually happen during vacuums. Should I be worried?
--
Martijn van Oosterhout <kleptog@svana.org>
http://svana.org/kleptog/
> It would be nice if someone came up with a certification system that
> actually separated those who can barely regurgitate what they crammed over
> the last few weeks from those who command secret ninja networking powers.

Re: Errors in logs

From
Philip Molter
Date:
On Wed, Jul 18, 2001 at 05:23:53PM +1000, Martijn van Oosterhout wrote:
: I'm getting these errors in my logs. they don't seem bad but I like to be
: sure:
:
: 010708.02:33:03.966  [2369] SIInsertDataEntry: table is 70% full, signaling postmaster
: 010708.02:33:03.966 [27400] pmdie 12
: 010708.02:33:03.991 [27400] SignalChildren: sending signal 12 to process 2369
: 010708.02:33:03.991 [27400] SignalChildren: sending signal 12 to process 24757
: 010708.02:33:03.991 [27400] SignalChildren: sending signal 12 to process 9657
: 010708.02:33:04.420  [9657] NOTICE:  InvalidateSharedInvalid: cache state reset
: 010708.04:00:03.924  [2535] NOTICE:  RegisterSharedInvalid: SI buffer overflow
: 010708.04:00:03.924  [2535] NOTICE:  InvalidateSharedInvalid: cache state reset
: 010710.04:09:26.357 [18323] NOTICE:  RegisterSharedInvalid: SI buffer overflow
: 010710.04:09:26.357 [18323] NOTICE:  InvalidateSharedInvalid: cache state reset
: 010711.02:45:25.467 [25307] SIInsertDataEntry: table is 70% full, signaling post master
: 010711.02:45:25.467 [27400] pmdie 12
: 010711.02:45:25.523 [27400] SignalChildren: sending signal 12 to process 25307
: 010711.02:45:25.523 [27400] SignalChildren: sending signal 12 to process 22906
: 010711.02:45:25.523 [27400] SignalChildren: sending signal 12 to process 24757
: 010711.04:03:48.358 [25494] NOTICE:  RegisterSharedInvalid: SI buffer overflow
: 010711.04:03:48.358 [25494] NOTICE:  InvalidateSharedInvalid: cache state reset

For the record, I've seen the same errors at various times through the
use of our database, and it's almost always during vacuums (7.1.2).

* Philip Molter
* DataFoundry.net
* http://www.datafoundry.net/
* philip@datafoundry.net

Re: Errors in logs

From
Tom Lane
Date:
Martijn van Oosterhout <kleptog@svana.org> writes:
> Seems to usually happen during vacuums. Should I be worried?

Nope.  You're just watching VACUUM notify the other backends that it's
moving system catalog entries around.

            regards, tom lane

Re: Errors in logs

From
"Richard Huxton"
Date:
From: "Philip Molter" <philip@datafoundry.net>

> On Wed, Jul 18, 2001 at 05:23:53PM +1000, Martijn van Oosterhout wrote:
> : I'm getting these errors in my logs. they don't seem bad but I like to
be
> : sure:
> :
> : 010708.02:33:03.966  [2369] SIInsertDataEntry: table is 70% full,
signaling postmaster
> : 010708.02:33:03.966 [27400] pmdie 12
> : 010708.02:33:03.991 [27400] SignalChildren: sending signal 12 to process
2369
> : 010708.02:33:03.991 [27400] SignalChildren: sending signal 12 to process
24757
> : 010708.02:33:03.991 [27400] SignalChildren: sending signal 12 to process
9657
> : 010708.02:33:04.420  [9657] NOTICE:  InvalidateSharedInvalid: cache
state reset
> : 010708.04:00:03.924  [2535] NOTICE:  RegisterSharedInvalid: SI buffer
overflow
> : 010708.04:00:03.924  [2535] NOTICE:  InvalidateSharedInvalid: cache
state reset

> For the record, I've seen the same errors at various times through the
> use of our database, and it's almost always during vacuums (7.1.2).
>

From the source:

       /*
         * Try to prevent table overflow.  When the table is 70% full send a
         * SIGUSR2 (ordinarily a NOTIFY signal) to the postmaster, which
will
         * send it back to all the backends.  This will force idle backends
to
         * execute a transaction to look through pg_listener for NOTIFY
         * messages, and as a byproduct of the transaction start they will
         * read SI entries.
         *
         * This should never happen if all the backends are actively
executing
         * queries, but if a backend is sitting idle then it won't be
starting
         * transactions and so won't be reading SI entries.
         *
         * dz - 27 Jan 1998
         */

Looks like it's allocating space or something, so invalidates any data in
backend caches.

- Richard Huxton


Re: Errors in logs

From
will trillich
Date:
On Wed, Jul 18, 2001 at 12:06:33PM -0400, Tom Lane wrote:
> Martijn van Oosterhout <kleptog@svana.org> writes:
> > Seems to usually happen during vacuums. Should I be worried?
>
> Nope.  You're just watching VACUUM notify the other backends that it's
> moving system catalog entries around.

this seems to be new in 7.1 (i don't recall seeing any such
messages in 7.0.3 or earlier).

i can see they say "notice" but the message sure sounds like a
fundamental pillar of the structure is about to collapse:

    NOTICE:  InvalidateSharedInvalid: cache state reset
    NOTICE:  RegisterSharedInvalid: SI buffer overflow

tick... tick... tick... but no boom, you say?

--
I'd concentrate on "living in the now" because it is fun
and on building a better world because it is possible.
    - Tod Steward

will@serensoft.com
http://sourceforge.net/projects/newbiedoc -- we need your brain!
http://www.dontUthink.com/ -- your brain needs us!

Re: Errors in logs

From
Tom Lane
Date:
will trillich <will@serensoft.com> writes:
>> Nope.  You're just watching VACUUM notify the other backends that it's
>> moving system catalog entries around.

> this seems to be new in 7.1 (i don't recall seeing any such
> messages in 7.0.3 or earlier).

You weren't looking closely, then, or weren't exercising the system
in the same fashion.

> i can see they say "notice" but the message sure sounds like a
> fundamental pillar of the structure is about to collapse:

They've been downgraded to "debug" messages in the current sources.

At one time there were enough bugs in the SI buffer overrun code
to make it worth warning people when it was being exercised :-( ...
but I believe we've squashed them all.  There's no reason to pay
attention to these messages any more.

            regards, tom lane