Re: Log message " last_statrequest ... is later than collector's time" - what does it mean?

From: Stephan Vollmer <vollmer(dot)stephan(at)googlemail(dot)com>
To: pgsql-general <pgsql-general(at)postgresql(dot)org>
Subject: Re: Log message " last_statrequest ... is later than collector's time" - what does it mean?
Date: 2011-09-16 20:00:38
Message-ID: CAA2gb+dP2_EV4vVt1zNa9aSiJA5z9oOJrZ6e9PfCQJLxN_1Zwg@mail.gmail.com
Views: Raw Message | Whole Thread | Download mbox | Resend email
Thread:
Lists: pgsql-general

On Fri, Sep 16, 2011 at 19:16, Tom Lane <tgl(at)sss(dot)pgh(dot)pa(dot)us> wrote:
>
> Stephan Vollmer <vollmer(dot)stephan(at)googlemail(dot)com> writes:
> > I upgraded our test database from PostgreSQL 8.4.8 to 9.0.4 via pg_dumpall.
> > The database seems to work fine, but now the logfile of the new database is
> > flooded with log messages like these:
>
> > 2011-09-16 13:48:54 CEST: LOG:  last_statrequest 2011-09-16
> > 13:48:55.890743+02 is later than collector's time 2011-09-16
> > 13:48:54.614476+02
>
> Wow.  AFAIK this is an indication of major system clock problems,
> as in there's at least one backend process that is seeing gettimeofday()
> results significantly later than what the stats collector process is
> seeing.  We have seen small processor-to-processor skews before, but
> you've apparently got skews that are more than a second.  It was
> presumably happening before too, but pre-9.0 the stats collector doesn't
> bleat about it --- we added that logging to try to diagnose such
> problems.
>
> Now it's relatively harmless so far as this particular issue goes (I
> think the log bleating is the only real consequence); but it's not hard
> to envision very serious problems elsewhere, for instance gmake failing
> to rebuild things because file timestamps are in the future compared to
> what it thinks the time is.  You want to get that fixed.

Thank you for this information! I agree that this problem should be
fixed, but unfortunately I'm not the server admin and there are other
applications running on this machine (even MySQL...). In the meantime,
is there a way to filter these log messages other than setting
"log_min_messages" to "fatal" which is not really desireable?

> > Configuration:
> > - SUSE Linux Enterprise Server 10 (i586)
> > - uname -a: Linux 2.6.16.21-0.8-bigsmp #1 SMP Mon Jul 3 18:25:39 UTC 2006
> > i686 athlon i386 GNU/Linux
>
> I'd file a bug report with SUSE.  But probably the first thing they'd
> say is you should be using a less ancient kernel, so maybe upgrade that
> first and see if the issue goes away.

I'll try to persuade the admin to install at least the latest patch
version of this old kernel.

Thanks for your help and kind regards,
Stephan

In response to

Responses

Browse pgsql-general by date

  From Date Subject
Next Message Tom Lane 2011-09-16 20:50:33 Re: Log message " last_statrequest ... is later than collector's time" - what does it mean?
Previous Message Vitor Reus 2011-09-16 19:50:06 CUDA Sorting