Thread: query speed depends on lifetime of frozen db?

query speed depends on lifetime of frozen db?

From
Andriy Tkachuk
Date:
Hi hackers.

There is ineresting behavior of some select query mentioned in $subj.

In working db this query takes:
real    3m10.219s
user    0m0.074s
sys     0m0.074s

it's interesting that vacuum or analyze or reinex not helpfull, BUT

if dump this db and create it again (whith another name maybe, no matter,
just for testing) and query the same query on this db, it takes:
real    0m6.225s
user    0m0.072s
sys     0m0.074s
(other databases continue running)

There is no end of this story!
With some time (couple of days for example) this the same query overloads
machine on this new test db also! No one working with this db during this
time. Works continued only with real working databases. Vacuuming was
as usual (every 2 hours without -f and with it at night one time) :
as i said this behavior does not depend on any vacuuming.

Have anyone any ideas about this?

db=# SELECT version();
                                version
-----------------------------------------------------------------------
 PostgreSQL 7.2.2 on i386-portbld-freebsd4.6.1, compiled by GCC 2.95.3


Thanks,
  Andriy.

--
  Because strait is the gate, and narrow is the way, which leadeth unto
   <b>life</b>, and few there be that find it. (MAT 7:14)
 <b>Ask</b>, and it shall be given you; <b>seek</b>, and ye shall find;
   <b>knock</b>, and it shall be opened unto you... (MAT 7:7)

ANT17-RIPE


Re: query speed depends on lifetime of frozen db?

From
"Shridhar Daithankar"
Date:
On 27 Sep 2002 at 10:58, Andriy Tkachuk wrote:

> Hi hackers.
>
> There is ineresting behavior of some select query mentioned in $subj.
>
> In working db this query takes:
> real    3m10.219s
> user    0m0.074s
> sys     0m0.074s
>
> it's interesting that vacuum or analyze or reinex not helpfull, BUT
>
> if dump this db and create it again (whith another name maybe, no matter,
> just for testing) and query the same query on this db, it takes:
> real    0m6.225s
> user    0m0.072s
> sys     0m0.074s
> (other databases continue running)

Looks like a database defrag to me...

> There is no end of this story!
> With some time (couple of days for example) this the same query overloads
> machine on this new test db also! No one working with this db during this
> time. Works continued only with real working databases. Vacuuming was
> as usual (every 2 hours without -f and with it at night one time) :
> as i said this behavior does not depend on any vacuuming.

was that vacuum full or vacuum analyze? Vacuum full should help in this case..

Is it that some tables with few rows gets updated heavily causing lot of dead
tuples? May be 2 hour is bit too long before vacuum should be called. Try
running table speific vacuum more periodically..

HTH...


Bye
 Shridhar

--
lawsuit, n.:    A machine which you go into as a pig and come out as a sausage.        --
 Ambrose Bierce


Re: query speed depends on lifetime of frozen db?

From
Andriy Tkachuk
Date:
On Fri, 27 Sep 2002, Shridhar Daithankar wrote:

> On 27 Sep 2002 at 10:58, Andriy Tkachuk wrote:
>
> > Hi hackers.
> >
> > There is ineresting behavior of some select query mentioned in $subj.
> >
> > In working db this query takes:
> > real    3m10.219s
> > user    0m0.074s
> > sys     0m0.074s
> >
> > it's interesting that vacuum or analyze or reinex not helpfull, BUT
> >
> > if dump this db and create it again (whith another name maybe, no matter,
> > just for testing) and query the same query on this db, it takes:
> > real    0m6.225s
> > user    0m0.072s
> > sys     0m0.074s
> > (other databases continue running)
>
> Looks like a database defrag to me...
>
> > There is no end of this story!
> > With some time (couple of days for example) this the same query overloads
> > machine on this new test db also! No one working with this db during this
> > time. Works continued only with real working databases. Vacuuming was
> > as usual (every 2 hours without -f and with it at night one time) :
> > as i said this behavior does not depend on any vacuuming.
>
> was that vacuum full or vacuum analyze? Vacuum full should help in this case..

it was full with analize
That's what i want to say, that this is very strange for me that vacuum
not helpfull in this situation!

>
> Is it that some tables with few rows gets updated heavily causing lot of dead
> tuples? May be 2 hour is bit too long before vacuum should be called. Try
> running table speific vacuum more periodically..

As I said there was no work with this test database during the time after which
query becomes overloading. There was just work with another databases.

Thanks,
  Andriy.


Re: query speed depends on lifetime of frozen db?

From
Martijn van Oosterhout
Date:
On Fri, Sep 27, 2002 at 11:49:08AM +0300, Andriy Tkachuk wrote:
> On Fri, 27 Sep 2002, Shridhar Daithankar wrote:
> > was that vacuum full or vacuum analyze? Vacuum full should help in this case..
>
> it was full with analize
> That's what i want to say, that this is very strange for me that vacuum
> not helpfull in this situation!

Ok, can you post the result of VACUUM FULL VERBOSE ANALYSE ?

> >
> > Is it that some tables with few rows gets updated heavily causing lot of dead
> > tuples? May be 2 hour is bit too long before vacuum should be called. Try
> > running table speific vacuum more periodically..
>
> As I said there was no work with this test database during the time after which
> query becomes overloading. There was just work with another databases.

Well, something is happening. The verbose in the above command should help.

--
Martijn van Oosterhout   <kleptog@svana.org>   http://svana.org/kleptog/
> There are 10 kinds of people in the world, those that can do binary
> arithmetic and those that can't.

Re: query speed depends on lifetime of frozen db?

From
"Shridhar Daithankar"
Date:
On 27 Sep 2002 at 11:49, Andriy Tkachuk wrote:
> As I said there was no work with this test database during the time after which
> query becomes overloading. There was just work with another databases.

From testing point of view, that is not much good. Try halting all work and
just do this testing. One variable at a time..

I understand it may be difficult if it's a production system but with other DBs
working on site, too many variables come into picture..

Bye
 Shridhar

--
But Captain -- the engines can't take this much longer!


Re: query speed depends on lifetime of frozen db?

From
Martijn van Oosterhout
Date:
On Fri, Sep 27, 2002 at 12:50:14PM +0300, Andriy Tkachuk wrote:
> On Fri, 27 Sep 2002, Martijn van Oosterhout wrote:
>
> > On Fri, Sep 27, 2002 at 11:49:08AM +0300, Andriy Tkachuk wrote:
> > > On Fri, 27 Sep 2002, Shridhar Daithankar wrote:
> > > > was that vacuum full or vacuum analyze? Vacuum full should help in this case..
> > >
> > > it was full with analize
> > > That's what i want to say, that this is very strange for me that vacuum
> > > not helpfull in this situation!
> >
> > Ok, can you post the result of VACUUM FULL VERBOSE ANALYSE ?

<snip>

Um, from the looks of that output, it seems your entire DB is less than 2MB,
right? So it should be totally cached. So it must be your query at fault.
What is the output of EXPLAIN ANALYSE <query>;


--
Martijn van Oosterhout   <kleptog@svana.org>   http://svana.org/kleptog/
> There are 10 kinds of people in the world, those that can do binary
> arithmetic and those that can't.

Re: query speed depends on lifetime of frozen db?

From
Andriy Tkachuk
Date:
On Fri, 27 Sep 2002, Shridhar Daithankar wrote:

> On 27 Sep 2002 at 11:49, Andriy Tkachuk wrote:
> > As I said there was no work with this test database during the time after which
> > query becomes overloading. There was just work with another databases.
>
> >From testing point of view, that is not much good. Try halting all work and
> just do this testing. One variable at a time..

All work was halted. Testing was true, in appropriate enviroment.

>
> I understand it may be difficult if it's a production system but with other DBs
> working on site, too many variables come into picture..

In testing time there was only our testing query, no others.
There was no any noticeable variable.

Thanks,
  Andriy.


Re: query speed depends on lifetime of frozen db?

From
Andriy Tkachuk
Date:
On Fri, 27 Sep 2002, Martijn van Oosterhout wrote:

> On Fri, Sep 27, 2002 at 12:50:14PM +0300, Andriy Tkachuk wrote:
> > On Fri, 27 Sep 2002, Martijn van Oosterhout wrote:
> >
> > > On Fri, Sep 27, 2002 at 11:49:08AM +0300, Andriy Tkachuk wrote:
> > > > On Fri, 27 Sep 2002, Shridhar Daithankar wrote:
> > > > > was that vacuum full or vacuum analyze? Vacuum full should help in this case..
> > > >
> > > > it was full with analize
> > > > That's what i want to say, that this is very strange for me that vacuum
> > > > not helpfull in this situation!
> > >
> > > Ok, can you post the result of VACUUM FULL VERBOSE ANALYSE ?
>
> <snip>
>
> Um, from the looks of that output, it seems your entire DB is less than 2MB,
> right? So it should be totally cached. So it must be your query at fault.
> What is the output of EXPLAIN ANALYSE <query>;

db ~ 10M, but i like your guess.

my OS:
Linux 2.4.9-13custom #1 Fri Feb 15 20:03:52 EST 2002 i686

There is EXPLAIN ANALYSE when query is heavy:

NOTICE:  QUERY PLAN:

Sort  (cost=26.09..26.09 rows=123 width=89) (actual time=168091.22..168091.31 rows=119 loops=1)
  ->  Hash Join  (cost=1.27..21.81 rows=123 width=89) (actual time=1404.81..168090.21 rows=119 loops=1)
        ->  Seq Scan on users u  (cost=0.00..18.07 rows=123 width=81) (actual time=0.14..5.67 rows=119 loops=1)
        ->  Hash  (cost=1.22..1.22 rows=22 width=8) (actual time=0.24..0.24 rows=0 loops=1)
              ->  Seq Scan on plans p  (cost=0.00..1.22 rows=22 width=8) (actual time=0.12..0.19 rows=22 loops=1)
        SubPlan
          ->  Seq Scan on plans  (cost=0.00..1.27 rows=1 width=7) (actual time=0.09..0.11 rows=1 loops=119)
          ->  Aggregate  (cost=23.80..23.80 rows=1 width=4) (actual time=0.93..0.94 rows=1 loops=119)
                ->  Seq Scan on oplaty  (cost=0.00..23.80 rows=1 width=4) (actual time=0.87..0.91 rows=0 loops=119)
          ->  Aggregate  (cost=20.84..20.84 rows=1 width=4) (actual time=0.85..0.86 rows=1 loops=119)
                ->  Seq Scan on oplaty  (cost=0.00..20.84 rows=1 width=4) (actual time=0.83..0.84 rows=0 loops=119)
          ->  Aggregate  (cost=22.32..22.32 rows=1 width=4) (actual time=0.84..0.85 rows=1 loops=119)
                ->  Seq Scan on oplaty  (cost=0.00..22.32 rows=1 width=4) (actual time=0.83..0.84 rows=0 loops=119)
          ->  Aggregate  (cost=216.00..216.00 rows=1 width=4) (actual time=1.27..1.27 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..215.88 rows=47 width=4) (actual time=0.25..1.18
rows=39loops=119) 
          ->  Aggregate  (cost=215.68..215.68 rows=1 width=4) (actual time=0.69..0.69 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..215.61 rows=30 width=4) (actual time=0.07..0.62
rows=32loops=119) 
          ->  Aggregate  (cost=215.68..215.68 rows=1 width=4) (actual time=0.69..0.69 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..215.61 rows=30 width=4) (actual time=0.06..0.62
rows=32loops=119) 
          ->  Aggregate  (cost=215.47..215.47 rows=1 width=4) (actual time=0.43..0.43 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..215.47 rows=1 width=4) (actual time=0.23..0.41
rows=3loops=119) 
          ->  Aggregate  (cost=215.47..215.47 rows=1 width=4) (actual time=0.44..0.44 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..215.47 rows=1 width=4) (actual time=0.23..0.43
rows=3loops=119) 
          ->  Aggregate  (cost=215.47..215.47 rows=1 width=4) (actual time=0.43..0.43 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..215.47 rows=1 width=4) (actual time=0.14..0.41
rows=4loops=119) 
          ->  Aggregate  (cost=215.47..215.47 rows=1 width=4) (actual time=0.43..0.43 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..215.47 rows=1 width=4) (actual time=0.14..0.41
rows=4loops=119) 
          ->  Aggregate  (cost=215.47..215.47 rows=1 width=4) (actual time=0.41..0.42 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..215.47 rows=1 width=4) (actual time=0.40..0.40
rows=0loops=119) 
          ->  Aggregate  (cost=216.44..216.44 rows=1 width=4) (actual time=0.76..0.76 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..216.44 rows=2 width=4) (actual time=0.61..0.74
rows=4loops=119) 
          ->  Aggregate  (cost=215.61..215.61 rows=1 width=4) (actual time=0.43..0.43 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..215.61 rows=1 width=4) (actual time=0.28..0.42
rows=1loops=119) 
Total runtime: 168092.92 msec

EXPLAIN


and there is, when query is light:

NOTICE:  QUERY PLAN:

Sort  (cost=28.90..28.90 rows=1 width=136) (actual time=3863.35..3863.43 rows=119 loops=1)
  ->  Hash Join  (cost=1.27..28.89 rows=1 width=136) (actual time=74.98..3861.69 rows=119 loops=1)
        ->  Seq Scan on users u  (cost=0.00..27.50 rows=10 width=128) (actual time=0.17..5.26 rows=119 loops=1)
        ->  Hash  (cost=1.22..1.22 rows=22 width=8) (actual time=0.16..0.16 rows=0 loops=1)
              ->  Seq Scan on plans p  (cost=0.00..1.22 rows=22 width=8) (actual time=0.03..0.11 rows=22 loops=1)
        SubPlan
          ->  Seq Scan on plans  (cost=0.00..1.27 rows=1 width=32) (actual time=0.03..0.05 rows=1 loops=119)
          ->  Aggregate  (cost=35.00..35.00 rows=1 width=4) (actual time=0.91..0.91 rows=1 loops=119)
                ->  Seq Scan on oplaty  (cost=0.00..35.00 rows=1 width=4) (actual time=0.85..0.89 rows=0 loops=119)
          ->  Aggregate  (cost=30.00..30.00 rows=1 width=4) (actual time=0.85..0.85 rows=1 loops=119)
                ->  Seq Scan on oplaty  (cost=0.00..30.00 rows=1 width=4) (actual time=0.83..0.84 rows=0 loops=119)
          ->  Aggregate  (cost=32.50..32.50 rows=1 width=4) (actual time=0.84..0.84 rows=1 loops=119)
                ->  Seq Scan on oplaty  (cost=0.00..32.50 rows=1 width=4) (actual time=0.83..0.83 rows=0 loops=119)
          ->  Aggregate  (cost=12.39..12.39 rows=1 width=4) (actual time=1.06..1.06 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..12.38 rows=1 width=4) (actual time=0.07..0.98
rows=39loops=119) 
          ->  Aggregate  (cost=12.37..12.37 rows=1 width=4) (actual time=0.69..0.69 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..12.37 rows=1 width=4) (actual time=0.07..0.62
rows=32loops=119) 
          ->  Aggregate  (cost=12.37..12.37 rows=1 width=4) (actual time=0.69..0.69 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..12.37 rows=1 width=4) (actual time=0.06..0.62
rows=32loops=119) 
          ->  Aggregate  (cost=12.37..12.37 rows=1 width=4) (actual time=0.43..0.43 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..12.36 rows=1 width=4) (actual time=0.23..0.41
rows=3loops=119) 
          ->  Aggregate  (cost=12.37..12.37 rows=1 width=4) (actual time=0.43..0.43 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..12.36 rows=1 width=4) (actual time=0.23..0.41
rows=3loops=119) 
          ->  Aggregate  (cost=12.37..12.37 rows=1 width=4) (actual time=0.43..0.44 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..12.36 rows=1 width=4) (actual time=0.14..0.41
rows=4loops=119) 
          ->  Aggregate  (cost=12.37..12.37 rows=1 width=4) (actual time=0.43..0.43 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..12.36 rows=1 width=4) (actual time=0.14..0.41
rows=4loops=119) 
          ->  Aggregate  (cost=12.37..12.37 rows=1 width=4) (actual time=0.41..0.41 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..12.36 rows=1 width=4) (actual time=0.40..0.40
rows=0loops=119) 
          ->  Aggregate  (cost=12.41..12.41 rows=1 width=4) (actual time=0.73..0.73 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..12.41 rows=1 width=4) (actual time=0.58..0.71
rows=4loops=119) 
          ->  Aggregate  (cost=12.37..12.37 rows=1 width=4) (actual time=0.42..0.42 rows=1 loops=119)
                ->  Index Scan using bill_uid on bills  (cost=0.00..12.37 rows=1 width=4) (actual time=0.27..0.41
rows=1loops=119) 
Total runtime: 3865.89 msec

EXPLAIN


Re: query speed depends on lifetime of frozen db?

From
Andriy Tkachuk
Date:
On Fri, 27 Sep 2002, Andriy Tkachuk wrote:

> On Fri, 27 Sep 2002, Martijn van Oosterhout wrote:
>
> > On Fri, Sep 27, 2002 at 12:50:14PM +0300, Andriy Tkachuk wrote:
> > > On Fri, 27 Sep 2002, Martijn van Oosterhout wrote:
> > >
> > > > On Fri, Sep 27, 2002 at 11:49:08AM +0300, Andriy Tkachuk wrote:
> > > > > On Fri, 27 Sep 2002, Shridhar Daithankar wrote:
> > > > > > was that vacuum full or vacuum analyze? Vacuum full should help in this case..
> > > > >
> > > > > it was full with analize
> > > > > That's what i want to say, that this is very strange for me that vacuum
> > > > > not helpfull in this situation!
> > > >
> > > > Ok, can you post the result of VACUUM FULL VERBOSE ANALYSE ?
> >
> > <snip>
> >
> > Um, from the looks of that output, it seems your entire DB is less than 2MB,
> > right? So it should be totally cached. So it must be your query at fault.
> > What is the output of EXPLAIN ANALYSE <query>;
>
> db ~ 10M, but i like your guess.
>
> my OS:
> Linux 2.4.9-13custom #1 Fri Feb 15 20:03:52 EST 2002 i686

and 256M phys_mem and
shared_buffers = 1024
...

i test it on linux and FreeBSD (with kern.ipc.shm_use_phys=1, ~400M phys_mem,
and soft-updates on UFS) and there is the same behavior so i think that
this problem is not OS specific.

Also just after dumping and restoring the test db i restart pg and
the query was fast though, so i think that it's not caching.

It seems like there are some relations between databases that speed
of this query depends on with time.

Thanks,
  Andriy.


Re: query speed depends on lifetime of frozen db?

From
Martijn van Oosterhout
Date:
On Fri, Sep 27, 2002 at 01:28:13PM +0300, Andriy Tkachuk wrote:
> On Fri, 27 Sep 2002, Martijn van Oosterhout wrote:
> > What is the output of EXPLAIN ANALYSE <query>;
>
> There is EXPLAIN ANALYSE when query is heavy:

Oookaaay. Your query is *evil*. 14 subqueries executed for *each* row of
output!?! I reackon you could improve your query just by rewriting it into a
better form. How can you have 10 subqueries to the same table?

Anyway, the only thing that seems to change is the statistics, which leads
me to beleive that all that is happening is that the planner is reordering some
of your clauses causing it to execute expensive ones it may otherwise be
able to avoid. In your case the default statistics do better than the real
ones.

I think I need to understand your query to help any further.

Snipped plans follow:

> NOTICE:  QUERY PLAN:
>
> Sort  (cost=26.09..26.09 rows=123 width=89) (actual time=168091.22..168091.31 rows=119 loops=1)
>   ->  Hash Join  (cost=1.27..21.81 rows=123 width=89) (actual time=1404.81..168090.21 rows=119 loops=1)
>         ->  Seq Scan on users u  (cost=0.00..18.07 rows=123 width=81) (actual time=0.14..5.67 rows=119 loops=1)
>         SubPlan
>           ->  Aggregate  (cost=215.61..215.61 rows=1 width=4) (actual time=0.43..0.43 rows=1 loops=119)
>                 ->  Index Scan using bill_uid on bills  (cost=0.00..215.61 rows=1 width=4) (actual time=0.28..0.42
rows=1loops=119) 
> Total runtime: 168092.92 msec
>
> EXPLAIN
>
>
> and there is, when query is light:
>
> NOTICE:  QUERY PLAN:
>
> Sort  (cost=28.90..28.90 rows=1 width=136) (actual time=3863.35..3863.43 rows=119 loops=1)
>   ->  Hash Join  (cost=1.27..28.89 rows=1 width=136) (actual time=74.98..3861.69 rows=119 loops=1)
>         ->  Seq Scan on users u  (cost=0.00..27.50 rows=10 width=128) (actual time=0.17..5.26 rows=119 loops=1)
>         ->  Hash  (cost=1.22..1.22 rows=22 width=8) (actual time=0.16..0.16 rows=0 loops=1)
>               ->  Seq Scan on plans p  (cost=0.00..1.22 rows=22 width=8) (actual time=0.03..0.11 rows=22 loops=1)
>         SubPlan
>           ->  Aggregate  (cost=12.37..12.37 rows=1 width=4) (actual time=0.69..0.69 rows=1 loops=119)
>                 ->  Index Scan using bill_uid on bills  (cost=0.00..12.37 rows=1 width=4) (actual time=0.06..0.62
rows=32loops=119) 
> Total runtime: 3865.89 msec
>
> EXPLAIN
--
Martijn van Oosterhout   <kleptog@svana.org>   http://svana.org/kleptog/
> There are 10 kinds of people in the world, those that can do binary
> arithmetic and those that can't.

Re: query speed depends on lifetime of frozen db?

From
Andriy Tkachuk
Date:
On Fri, 27 Sep 2002, Martijn van Oosterhout wrote:

> On Fri, Sep 27, 2002 at 01:28:13PM +0300, Andriy Tkachuk wrote:
> > On Fri, 27 Sep 2002, Martijn van Oosterhout wrote:
> > > What is the output of EXPLAIN ANALYSE <query>;
> >
> > There is EXPLAIN ANALYSE when query is heavy:
>
> Oookaaay. Your query is *evil*. 14 subqueries executed for *each* row of
> output!?! I reackon you could improve your query just by rewriting it into a
> better form. How can you have 10 subqueries to the same table?
>
> Anyway, the only thing that seems to change is the statistics, which leads
> me to beleive that all that is happening is that the planner is reordering some
> of your clauses causing it to execute expensive ones it may otherwise be
> able to avoid. In your case the default statistics do better than the real
> ones.

YES! You right!
Just after restirong db i made vacuumdb -z -f
and query become heavy!

Does one have any ideas how to ovecome this!?

Thanks a lot Martijn,
  Andriy.


Re: query speed depends on lifetime of frozen db?

From
Tom Lane
Date:
Andriy Tkachuk <ant@imt.com.ua> writes:
> There is EXPLAIN ANALYSE when query is heavy:
> ...
> and there is, when query is light:
> ...

These sure appear to be the same query plan.  I am thinking that the
cost differential is not in the plan itself at all, but in some bit of
processing that doesn't show in the plan.  In particular, since all the
extra runtime shows up in the top join node (where the SELECT result
list would be evaluated), I am thinking that there's something funny
going on in some user-defined function that's called in the SELECT list.
You have not shown us the actual query yet, AFAIR.  Any
potentially-expensive functions in there?

            regards, tom lane

Re: query speed depends on lifetime of frozen db?

From
Martijn van Oosterhout
Date:
On Fri, Sep 27, 2002 at 05:58:05PM +0300, Andriy Tkachuk wrote:
> On Fri, 27 Sep 2002, Martijn van Oosterhout wrote:
>
> > On Fri, Sep 27, 2002 at 01:28:13PM +0300, Andriy Tkachuk wrote:
> > > On Fri, 27 Sep 2002, Martijn van Oosterhout wrote:
> > > > What is the output of EXPLAIN ANALYSE <query>;
> > >
> > > There is EXPLAIN ANALYSE when query is heavy:
> >
> > Oookaaay. Your query is *evil*. 14 subqueries executed for *each* row of
> > output!?! I reackon you could improve your query just by rewriting it into a
> > better form. How can you have 10 subqueries to the same table?
>
> YES! You right!
> Just after restirong db i made vacuumdb -z -f
> and query become heavy!
>
> Does one have any ideas how to ovecome this!?


Firstly, how is calc_account() defined? Is it doing subqueries? If it is
then the planner won't be seeing them. Is it optimised?

        calc_account (u.uid, 1030827600) as start_account,
        calc_account (u.uid, 1032178388) as end_account,

--
Martijn van Oosterhout   <kleptog@svana.org>   http://svana.org/kleptog/
> There are 10 kinds of people in the world, those that can do binary
> arithmetic and those that can't.

Re: query speed depends on lifetime of frozen db?

From
Martijn van Oosterhout
Date:
Ok. Please send stuff like this to the list, that's what it's there for.

Secondly, I have no idea why this is happening, you'll have to find someone
else who knows...

On Sat, Sep 28, 2002 at 02:02:19PM +0300, Andriy Tkachuk wrote:
> > what does:
> >
> > EXPLAIN ANALYZE calc_account (u.uid, 1030827600);
> >
> > do when the big query is both fast and when it's slow. I reackon that
> > function is where the time is taken.
>
> Guys, there are many interesting things:
>
> 1: (db is clean: just restored from dump)
>
> calc_account have such query:
>
> bb=# EXPLAIN ANALYZE select sum(cost) as cost from bills b,users u where u.parent= 12608 and b.dat between 0 and
1030827600and b.uid = u.uid; 
> NOTICE:  QUERY PLAN:
>
> Aggregate  (cost=84.54..84.54 rows=1 width=12) (actual time=0.96..0.96 rows=1 loops=1)
>   ->  Nested Loop  (cost=0.00..84.51 rows=13 width=12) (actual time=0.94..0.94 rows=0 loops=1)
>         ->  Seq Scan on users u  (cost=0.00..22.50 rows=5 width=4) (actual time=0.93..0.93 rows=0 loops=1)
>         ->  Index Scan using bill_uid on bills b  (cost=0.00..12.37 rows=3 width=8)
> Total runtime: 1.13 msec
>
> EXPLAIN
> bb=# ANALYZE bills;
> ANALYZE
> bb=# EXPLAIN ANALYZE select sum(cost) as cost from bills b,users u where u.parent= 12608 and b.dat between 0 and
1030827600and b.uid = u.uid; 
> NOTICE:  QUERY PLAN:
>
> Aggregate  (cost=3391.67..3391.67 rows=1 width=12) (actual time=2033.08..2033.09 rows=1 loops=1)
>   ->  Hash Join  (cost=22.51..3386.47 rows=2079 width=12) (actual time=2033.06..2033.06 rows=0 loops=1)
>         ->  Seq Scan on bills b  (cost=0.00..2922.07 rows=83177 width=8) (actual time=0.10..1446.82 rows=87125
loops=1)
>         ->  Hash  (cost=22.50..22.50 rows=5 width=4) (actual time=1.87..1.87 rows=0 loops=1)
>               ->  Seq Scan on users u  (cost=0.00..22.50 rows=5 width=4) (actual time=1.85..1.85 rows=0 loops=1)
> Total runtime: 2033.25 msec
>
> EXPLAIN
>
> WOW!!!
>
> BUT!
> 2. (again after dropping & restoring db)
>
> bb=# EXPLAIN ANALYZE SELECT calc_account(12608, 1030827600);
> NOTICE:  QUERY PLAN:
>
> Result  (cost=0.00..0.01 rows=1 width=0) (actual time=251.83..251.83 rows=1 loops=1)
> Total runtime: 251.89 msec
>
> EXPLAIN
> bb=# ANALYZE bills;
> ANALYZE
> bb=# EXPLAIN ANALYZE SELECT calc_account(12608, 1030827600);
> NOTICE:  QUERY PLAN:
>
> Result  (cost=0.00..0.01 rows=1 width=0) (actual time=284.00..284.01 rows=1 loops=1)
> Total runtime: 284.14 msec
>
> EXPLAIN
>
>
> and just after ANALYZING bills the main query still performs fast.
>
> bb=# ANALYZE users;
> ANALYZE
> bb=# EXPLAIN ANALYZE SELECT calc_account(12608, 1030827600);
> NOTICE:  QUERY PLAN:
>
> Result  (cost=0.00..0.01 rows=1 width=0) (actual time=199.52..199.53 rows=1 loops=1)
> Total runtime: 199.58 msec
>
> EXPLAIN
>
>
> and now the main query overloads.
>

--
Martijn van Oosterhout   <kleptog@svana.org>   http://svana.org/kleptog/
> There are 10 kinds of people in the world, those that can do binary
> arithmetic and those that can't.

Re: query speed depends on lifetime of frozen db?.. Sorry

From
Andriy Tkachuk
Date:
Hi all,

does one have any idea why there is so incredible differences
between plans:

bb=# EXPLAIN ANALYZE select * from bills b, users u where b.uid = u.uid and u.uid=10;
NOTICE:  QUERY PLAN:

Nested Loop  (cost=0.00..1874.57 rows=356 width=403) (actual time=0.18..0.18 rows=0 loops=1)
  ->  Index Scan using users_uid_idx on users u  (cost=0.00..5.79 rows=1 width=301) (actual time=0.17..0.17 rows=0
loops=1)
  ->  Index Scan using bill_uid on bills b  (cost=0.00..1861.69 rows=567 width=102)
Total runtime: 0.67 msec

EXPLAIN
bb=# EXPLAIN ANALYZE select *  from bills b, users u where b.uid = u.uid and u.parent=10;
NOTICE:  QUERY PLAN:

Hash Join  (cost=6.49..3010.47 rows=2016 width=403) (actual time=4831.21..4831.21 rows=0 loops=1)
  ->  Seq Scan on bills b  (cost=0.00..2462.85 rows=103185 width=102) (actual time=0.11..3687.68 rows=103185 loops=1)
  ->  Hash  (cost=6.48..6.48 rows=6 width=301) (actual time=0.20..0.20 rows=0 loops=1)
        ->  Index Scan using users_parent_idx on users u  (cost=0.00..6.48 rows=6 width=301) (actual time=0.19..0.19
rows=0loops=1) 
Total runtime: 4831.84 msec

EXPLAIN
bb=# SELECT count(*) from users where parent = 10;
 count
-------
     0
(1 row)


Thanks,
  Andriy.


Re: query speed depends on lifetime of frozen db?

From
Andriy Tkachuk
Date:
On Fri, 27 Sep 2002, Martijn van Oosterhout wrote:

> On Fri, Sep 27, 2002 at 11:49:08AM +0300, Andriy Tkachuk wrote:
> > On Fri, 27 Sep 2002, Shridhar Daithankar wrote:
> > > was that vacuum full or vacuum analyze? Vacuum full should help in this case..
> >
> > it was full with analize
> > That's what i want to say, that this is very strange for me that vacuum
> > not helpfull in this situation!
>
> Ok, can you post the result of VACUUM FULL VERBOSE ANALYSE ?
>
> > >
> > > Is it that some tables with few rows gets updated heavily causing lot of dead
> > > tuples? May be 2 hour is bit too long before vacuum should be called. Try
> > > running table speific vacuum more periodically..
> >
> > As I said there was no work with this test database during the time after which
> > query becomes overloading. There was just work with another databases.
>
> Well, something is happening. The verbose in the above command should help.

here it is:

NOTICE:  --Relation pg_type--
NOTICE:  Pages 6: Changed 0, reaped 0, Empty 0, New 0; Tup 411: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 106, MaxLen 106;
Re-using:Free/Avail. Space 3000/3000; EndEmpty/Avail. Pages 0/6. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_type_oid_index: Pages 2; Tuples 411.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_type_typname_index: Pages 6; Tuples 411.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_type: Pages: 6 --> 6; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_type
NOTICE:  --Relation pg_attribute--
NOTICE:  Pages 55: Changed 0, reaped 1, Empty 0, New 0; Tup 4226: Vac 0, Keep/VTL 0/0, UnUsed 27, MinLen 98, MaxLen 98;
Re-using:Free/Avail. Space 9848/6608; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_attribute_relid_attnam_index: Pages 41; Tuples 4226: Deleted 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_attribute_relid_attnum_index: Pages 18; Tuples 4226: Deleted 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_attribute: Pages: 55 --> 55; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_attribute
NOTICE:  --Relation pg_class--
NOTICE:  Pages 8: Changed 0, reaped 5, Empty 0, New 0; Tup 504: Vac 0, Keep/VTL 0/0, UnUsed 9, MinLen 116, MaxLen 176;
Re-using:Free/Avail. Space 828/496; EndEmpty/Avail. Pages 0/2. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_class_oid_index: Pages 6; Tuples 504: Deleted 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_class_relname_index: Pages 12; Tuples 504: Deleted 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_class: Pages: 8 --> 8; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_class
NOTICE:  --Relation pg_group--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_group_name_index: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_group_sysid_index: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_group
NOTICE:  --Relation pg_database--
NOTICE:  Pages 1: Changed 0, reaped 1, Empty 0, New 0; Tup 12: Vac 0, Keep/VTL 0/0, UnUsed 13, MinLen 92, MaxLen 92;
Re-using:Free/Avail. Space 6968/6968; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_database_datname_index: Pages 2; Tuples 12: Deleted 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_database_oid_index: Pages 2; Tuples 12: Deleted 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_database: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_database
NOTICE:  --Relation pg_inherits--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_inherits_relid_seqno_index: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_inherits
NOTICE:  --Relation pg_index--
NOTICE:  Pages 4: Changed 0, reaped 0, Empty 0, New 0; Tup 182: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 160, MaxLen 160;
Re-using:Free/Avail. Space 2840/2432; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_index_indrelid_index: Pages 2; Tuples 182.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_index_indexrelid_index: Pages 2; Tuples 182.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_index: Pages: 4 --> 4; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_index
NOTICE:  --Relation pg_operator--
NOTICE:  Pages 10: Changed 0, reaped 0, Empty 0, New 0; Tup 623: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 116, MaxLen 116;
Re-using:Free/Avail. Space 6960/6852; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_operator_oid_index: Pages 4; Tuples 623.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_operator_oprname_l_r_k_index: Pages 8; Tuples 623.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_operator: Pages: 10 --> 10; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_operator
NOTICE:  --Relation pg_opclass--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 51: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 80, MaxLen 80;
Re-using:Free/Avail. Space 3888/3888; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_opclass_am_name_index: Pages 2; Tuples 51.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_opclass_oid_index: Pages 2; Tuples 51.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_opclass: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_opclass
NOTICE:  --Relation pg_am--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 4: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 120, MaxLen 120;
Re-using:Free/Avail. Space 7676/7676; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_am_name_index: Pages 2; Tuples 4.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_am_oid_index: Pages 2; Tuples 4.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_am: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_am
NOTICE:  --Relation pg_amop--
NOTICE:  Pages 2: Changed 0, reaped 0, Empty 0, New 0; Tup 180: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 44, MaxLen 44;
Re-using:Free/Avail. Space 7704/7692; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_amop_opc_opr_index: Pages 2; Tuples 180.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_amop_opc_strategy_index: Pages 2; Tuples 180.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_amop: Pages: 2 --> 2; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_amop
NOTICE:  --Relation pg_amproc--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 57: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 44, MaxLen 44;
Re-using:Free/Avail. Space 5436/5436; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_amproc_opc_procnum_index: Pages 2; Tuples 57.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_amproc: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_amproc
NOTICE:  --Relation pg_language--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 5: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 76, MaxLen 84;
Re-using:Free/Avail. Space 7744/7744; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_language_name_index: Pages 2; Tuples 5.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_language_oid_index: Pages 2; Tuples 5.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_language: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_language
NOTICE:  --Relation pg_largeobject--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_largeobject_loid_pn_index: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_largeobject
NOTICE:  --Relation pg_aggregate--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 60: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 92, MaxLen 111;
Re-using:Free/Avail. Space 2244/2244; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_aggregate_name_type_index: Pages 2; Tuples 60.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_aggregate_oid_index: Pages 2; Tuples 60.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_aggregate: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_aggregate
NOTICE:  --Relation pg_trigger--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 11: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 152, MaxLen 228;
Re-using:Free/Avail. Space 5868/5868; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_trigger_tgconstrname_index: Pages 2; Tuples 11.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_trigger_tgconstrrelid_index: Pages 2; Tuples 11.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_trigger_tgrelid_index: Pages 2; Tuples 11.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_trigger_oid_index: Pages 2; Tuples 11.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_trigger: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_trigger
NOTICE:  --Relation pg_listener--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_listener
NOTICE:  --Relation pg_shadow--
NOTICE:  Pages 1: Changed 0, reaped 1, Empty 0, New 0; Tup 8: Vac 0, Keep/VTL 0/0, UnUsed 2, MinLen 76, MaxLen 76;
Re-using:Free/Avail. Space 7524/7524; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_shadow_usename_index: Pages 2; Tuples 8: Deleted 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_shadow_usesysid_index: Pages 2; Tuples 8: Deleted 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_shadow: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_shadow
NOTICE:  --Relation auoto_reach--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index auoto_reach_id_key: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing auoto_reach
NOTICE:  --Relation pg_attrdef--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 27: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 140, MaxLen 370;
Re-using:Free/Avail. Space 2820/2820; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_attrdef_adrelid_adnum_index: Pages 2; Tuples 27.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_attrdef: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_16384--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_16384_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_attrdef
NOTICE:  --Relation pg_description--
NOTICE:  Pages 12: Changed 0, reaped 1, Empty 0, New 0; Tup 1303: Vac 0, Keep/VTL 0/0, UnUsed 1, MinLen 50, MaxLen 118;
Re-using:Free/Avail. Space 3712/3468; EndEmpty/Avail. Pages 0/4. 
    CPU 0.01s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_description_o_c_o_index: Pages 7; Tuples 1303: Deleted 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_description: Pages: 12 --> 12; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_16416--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_16416_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_description
NOTICE:  --Relation areas--
NOTICE:  Pages 21: Changed 0, reaped 0, Empty 0, New 0; Tup 1199: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 53, MaxLen 184;
Re-using:Free/Avail. Space 5688/5208; EndEmpty/Avail. Pages 0/8. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index areas_prefix: Pages 6; Tuples 1199.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel areas: Pages: 21 --> 21; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202896--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202896_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing areas
NOTICE:  --Relation pg_proc--
NOTICE:  Pages 33: Changed 0, reaped 0, Empty 0, New 0; Tup 1314: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 177, MaxLen
1845;Re-using: Free/Avail. Space 4944/2524; EndEmpty/Avail. Pages 0/3. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_proc_oid_index: Pages 6; Tuples 1314.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_proc_proname_narg_type_index: Pages 30; Tuples 1314.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_proc: Pages: 33 --> 33; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_1255--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_1255_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_proc
NOTICE:  --Relation pg_relcheck--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_relcheck_rcrelid_index: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_16386--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_16386_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_relcheck
NOTICE:  --Relation pg_rewrite--
NOTICE:  Pages 4: Changed 0, reaped 0, Empty 0, New 0; Tup 23: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 104, MaxLen 1456;
Re-using:Free/Avail. Space 8496/8496; EndEmpty/Avail. Pages 0/4. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_rewrite_oid_index: Pages 2; Tuples 23.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_rewrite_rulename_index: Pages 2; Tuples 23.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_rewrite: Pages: 4 --> 4; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_16410--
NOTICE:  Pages 2: Changed 0, reaped 0, Empty 0, New 0; Tup 5: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 163, MaxLen 2034;
Re-using:Free/Avail. Space 8088/8088; EndEmpty/Avail. Pages 0/2. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_16410_idx: Pages 2; Tuples 5.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pg_toast_16410: Pages: 2 --> 2; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pg_rewrite
NOTICE:  --Relation plans--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 22: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 60, MaxLen 100;
Re-using:Free/Avail. Space 6200/6200; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pl_id: Pages 2; Tuples 22.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel plans: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202903--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202903_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing plans
NOTICE:  --Relation gateway_number--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 3: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 44, MaxLen 44;
Re-using:Free/Avail. Space 8028/8028; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index gateway_number_id_key: Pages 2; Tuples 3.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel gateway_number: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing gateway_number
NOTICE:  --Relation sessions--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202305--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202305_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing sessions
NOTICE:  --Relation typ_bills--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 15: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 50, MaxLen 75;
Re-using:Free/Avail. Space 7136/7136; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index typ_bills_id_key: Pages 2; Tuples 15.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel typ_bills: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing typ_bills
NOTICE:  --Relation pg_statistic--
NOTICE:  Pages 27: Changed 27, reaped 13, Empty 0, New 0; Tup 395: Vac 395, Keep/VTL 0/0, UnUsed 106, MinLen 80, MaxLen
1448;Re-using: Free/Avail. Space 113664/113584; EndEmpty/Avail. Pages 0/25. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_statistic_relid_att_index: Pages 5; Tuples 395: Deleted 395.
    CPU 0.00s/0.01u sec elapsed 0.00 sec.
NOTICE:  Rel pg_statistic: Pages: 27 --> 13; Tuple(s) moved: 393.
    CPU 0.00s/0.01u sec elapsed 0.02 sec.
NOTICE:  Index pg_statistic_relid_att_index: Pages 5; Tuples 395: Deleted 393.
    CPU 0.00s/0.01u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_16408--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_16408_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation users_update_col--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 12: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 60, MaxLen 159;
Re-using:Free/Avail. Space 7200/7200; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index users_update_col_id_key: Pages 2; Tuples 12.
    CPU 0.01s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel users_update_col: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202951--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202951_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing users_update_col
NOTICE:  --Relation clients--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202310--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202310_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing clients
NOTICE:  --Relation specphones--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 4: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 47, MaxLen 47;
Re-using:Free/Avail. Space 7964/7964; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index specphones_id_key: Pages 2; Tuples 4.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel specphones: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing specphones
NOTICE:  --Relation oplaty--
NOTICE:  Pages 9: Changed 0, reaped 0, Empty 0, New 0; Tup 592: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 82, MaxLen 198;
Re-using:Free/Avail. Space 3528/3140; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel oplaty: Pages: 9 --> 9; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202544--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202544_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing oplaty
NOTICE:  --Relation lines_names--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing lines_names
NOTICE:  --Relation lines_release_causes--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index lines_names_id_key: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing lines_release_causes
NOTICE:  --Relation lines_users--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index lines_users_id_key: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing lines_users
NOTICE:  --Relation lines_log--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing lines_log
NOTICE:  --Relation lines--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202315--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202315_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing lines
NOTICE:  --Relation auth_user--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 15: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 82, MaxLen 161;
Re-using:Free/Avail. Space 6520/6520; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index auth_user_pkey: Pages 2; Tuples 15.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel auth_user: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202335--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202335_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing auth_user
NOTICE:  --Relation bills--
NOTICE:  Pages 1431: Changed 0, reaped 0, Empty 0, New 0; Tup 103185: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 60, MaxLen
236;Re-using: Free/Avail. Space 52408/22520; EndEmpty/Avail. Pages 0/269. 
    CPU 0.10s/0.01u sec elapsed 0.10 sec.
NOTICE:  Index bills_dat: Pages 228; Tuples 103185.
    CPU 0.02s/0.00u sec elapsed 0.02 sec.
NOTICE:  Index bill_uid: Pages 284; Tuples 103185.
    CPU 0.02s/0.01u sec elapsed 0.02 sec.
NOTICE:  Rel bills: Pages: 1431 --> 1431; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202340--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202340_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing bills
NOTICE:  --Relation line_types--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202320--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202320_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing line_types
NOTICE:  --Relation areas_old--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index areas_id: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202390--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202390_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing areas_old
NOTICE:  --Relation pltcl_modfuncs--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 23: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 96, MaxLen 96;
Re-using:Free/Avail. Space 5872/5872; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pltcl_modfuncs_i: Pages 4; Tuples 23.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pltcl_modfuncs: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pltcl_modfuncs
NOTICE:  --Relation pltcl_modules--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 5: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 797, MaxLen 1854;
Re-using:Free/Avail. Space 1588/1588; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pltcl_modules_i: Pages 2; Tuples 5.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel pltcl_modules: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202402--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202402_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pltcl_modules
NOTICE:  --Relation callbacks--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 34: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 72, MaxLen 232;
Re-using:Free/Avail. Space 1836/1836; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel callbacks: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202325--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202325_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing callbacks
NOTICE:  --Relation zone4area--
NOTICE:  Pages 8: Changed 0, reaped 0, Empty 0, New 0; Tup 1216: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 48, MaxLen 48;
Re-using:Free/Avail. Space 2144/2088; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index z4a_area: Pages 5; Tuples 1216.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel zone4area: Pages: 8 --> 8; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing zone4area
NOTICE:  --Relation cards--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index cards_uid: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202479--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202479_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing cards
NOTICE:  --Relation new_countries--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index nc_zone: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202594--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202594_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing new_countries
NOTICE:  --Relation context--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index ctx_name: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index ctx_idi: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing context
NOTICE:  --Relation context_chunk--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index cx_chunk: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202792--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202792_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing context_chunk
NOTICE:  --Relation calls--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index calls_dat_index: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202820--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202820_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing calls
NOTICE:  --Relation sm_temp--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index sm_temp_id_key: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing sm_temp
NOTICE:  --Relation abon_bill_freq_types--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 2: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 45, MaxLen 47;
Re-using:Free/Avail. Space 8068/8068; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel abon_bill_freq_types: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202912--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202912_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing abon_bill_freq_types
NOTICE:  --Relation plans_ch_journal--
NOTICE:  Pages 3: Changed 0, reaped 0, Empty 0, New 0; Tup 115: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 126, MaxLen 144;
Re-using:Free/Avail. Space 8020/8020; EndEmpty/Avail. Pages 0/2. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel plans_ch_journal: Pages: 3 --> 3; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202921--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202921_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing plans_ch_journal
NOTICE:  --Relation sms_account--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 4: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 43, MaxLen 52;
Re-using:Free/Avail. Space 7956/7956; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel sms_account: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202931--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202931_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing sms_account
NOTICE:  --Relation golden_stat--
NOTICE:  Pages 4: Changed 0, reaped 0, Empty 0, New 0; Tup 109: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 207, MaxLen 513;
Re-using:Free/Avail. Space 7992/7516; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel golden_stat: Pages: 4 --> 4; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing golden_stat
NOTICE:  --Relation zal_types--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 2: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 51, MaxLen 58;
Re-using:Free/Avail. Space 8052/8052; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel zal_types: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202942--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202942_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing zal_types
NOTICE:  --Relation users_update--
NOTICE:  Pages 5: Changed 0, reaped 0, Empty 0, New 0; Tup 515: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 56, MaxLen 76;
Re-using:Free/Avail. Space 1496/1396; EndEmpty/Avail. Pages 0/2. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel users_update: Pages: 5 --> 5; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing users_update
NOTICE:  --Relation groups--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202345--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202345_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing groups
NOTICE:  --Relation managers--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202960--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202960_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing managers
NOTICE:  --Relation users--
NOTICE:  Pages 13: Changed 0, reaped 0, Empty 0, New 0; Tup 290: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 118, MaxLen 602;
Re-using:Free/Avail. Space 5036/4520; EndEmpty/Avail. Pages 0/8. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel users: Pages: 13 --> 13; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202970--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202970_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing users
NOTICE:  --Relation user_states--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 3: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 52, MaxLen 56;
Re-using:Free/Avail. Space 8000/8000; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel user_states: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202350--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202350_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing user_states
NOTICE:  --Relation voice_mailbox--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing voice_mailbox
NOTICE:  --Relation providers--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 3: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 42, MaxLen 43;
Re-using:Free/Avail. Space 8028/8028; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel providers: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing providers
NOTICE:  --Relation active_sessions--
NOTICE:  Pages 14: Changed 0, reaped 0, Empty 0, New 0; Tup 595: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 138, MaxLen
1890;Re-using: Free/Avail. Space 9128/8692; EndEmpty/Avail. Pages 0/5. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index sid_idx: Pages 4; Tuples 595.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel active_sessions: Pages: 14 --> 14; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202330--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202330_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing active_sessions
NOTICE:  --Relation params--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202355--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202355_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing params
NOTICE:  --Relation registry--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202837--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202837_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing registry
NOTICE:  --Relation fb_modes--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202360--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202360_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing fb_modes
NOTICE:  --Relation tmp_b--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202842--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202842_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing tmp_b
NOTICE:  --Relation currensy--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing currensy
NOTICE:  --Relation regions--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 20: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 48, MaxLen 60;
Re-using:Free/Avail. Space 7000/7000; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel regions: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing regions
NOTICE:  --Relation callback_types--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 5: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 60, MaxLen 64;
Re-using:Free/Avail. Space 7844/7844; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel callback_types: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202365--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202365_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing callback_types
NOTICE:  --Relation abonka--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 1: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 48, MaxLen 48;
Re-using:Free/Avail. Space 8120/8120; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel abonka: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing abonka
NOTICE:  --Relation courses--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 9: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 48, MaxLen 48;
Re-using:Free/Avail. Space 7704/7704; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel courses: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing courses
NOTICE:  --Relation auth--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202802--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202802_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing auth
NOTICE:  --Relation cb_types--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 6: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 48, MaxLen 52;
Re-using:Free/Avail. Space 7852/7852; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel cb_types: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202370--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202370_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing cb_types
NOTICE:  --Relation reply--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202807--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202807_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing reply
NOTICE:  --Relation log--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing log
NOTICE:  --Relation d_types--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202375--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202375_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing d_types
NOTICE:  --Relation sm_stat--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing sm_stat
NOTICE:  --Relation sm_reg--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing sm_reg
NOTICE:  --Relation sm_usr--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing sm_usr
NOTICE:  --Relation period_types--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202380--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202380_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing period_types
NOTICE:  --Relation _oldobjects--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202827--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202827_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing _oldobjects
NOTICE:  --Relation periods--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202385--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202385_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing periods
NOTICE:  --Relation mycalls--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202832--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202832_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing mycalls
NOTICE:  --Relation menus--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202775--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202775_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing menus
NOTICE:  --Relation vox--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202780--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202780_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing vox
NOTICE:  --Relation realms--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202785--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202785_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing realms
NOTICE:  --Relation messages--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202395--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202395_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing messages
NOTICE:  --Relation oprosy--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202797--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202797_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing oprosy
NOTICE:  --Relation tele_areas_backup--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202733--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202733_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing tele_areas_backup
NOTICE:  --Relation tpl_prices--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202738--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202738_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing tpl_prices
NOTICE:  --Relation gt_zone--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202743--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202743_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing gt_zone
NOTICE:  --Relation n_tpl_prices--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202748--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202748_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing n_tpl_prices
NOTICE:  --Relation old_prices--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202753--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202753_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing old_prices
NOTICE:  --Relation prices--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 69: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 52, MaxLen 56;
Re-using:Free/Avail. Space 4096/4096; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel prices: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing prices
NOTICE:  --Relation servers--
NOTICE:  Pages 3: Changed 0, reaped 0, Empty 0, New 0; Tup 329: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 68, MaxLen 68;
Re-using:Free/Avail. Space 828/756; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel servers: Pages: 3 --> 3; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202760--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202760_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing servers
NOTICE:  --Relation zone_types--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 3: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 43, MaxLen 44;
Re-using:Free/Avail. Space 8028/8028; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel zone_types: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202409--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202409_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing zone_types
NOTICE:  --Relation temp_pbx_calls--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202765--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202765_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing temp_pbx_calls
NOTICE:  --Relation objects--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202770--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202770_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing objects
NOTICE:  --Relation pbx_ani--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202691--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202691_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pbx_ani
NOTICE:  --Relation zones--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 20: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 72, MaxLen 120;
Re-using:Free/Avail. Space 6244/6244; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel zones: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202414--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202414_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing zones
NOTICE:  --Relation temp_bubu--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202696--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202696_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing temp_bubu
NOTICE:  --Relation tele_src--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202419--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202419_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing tele_src
NOTICE:  --Relation users_backup--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202701--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202701_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing users_backup
NOTICE:  --Relation prints--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 55: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 48, MaxLen 48;
Re-using:Free/Avail. Space 5312/5312; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel prints: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing prints
NOTICE:  --Relation prints_val--
NOTICE:  Pages 5: Changed 0, reaped 0, Empty 0, New 0; Tup 586: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 52, MaxLen 70;
Re-using:Free/Avail. Space 2488/2376; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel prints_val: Pages: 5 --> 5; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202708--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202708_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing prints_val
NOTICE:  --Relation opl_types--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 5: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 51, MaxLen 59;
Re-using:Free/Avail. Space 7860/7860; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel opl_types: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202424--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202424_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing opl_types
NOTICE:  --Relation ani_descr--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 40: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 71, MaxLen 103;
Re-using:Free/Avail. Space 4776/4776; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel ani_descr: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202713--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202713_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing ani_descr
NOTICE:  --Relation fsm_chunk--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202429--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202429_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing fsm_chunk
NOTICE:  --Relation tele_areas--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202718--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202718_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing tele_areas
NOTICE:  --Relation tele_zones--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202723--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202723_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing tele_zones
NOTICE:  --Relation fsm_object--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202434--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202434_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing fsm_object
NOTICE:  --Relation t2--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202728--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202728_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing t2
NOTICE:  --Relation notify_events--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202647--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202647_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing notify_events
NOTICE:  --Relation fsm_states--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202439--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202439_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing fsm_states
NOTICE:  --Relation events--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202652--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202652_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing events
NOTICE:  --Relation fsm--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202444--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202444_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing fsm
NOTICE:  --Relation event_ttl--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 5: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 48, MaxLen 76;
Re-using:Free/Avail. Space 7820/7820; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel event_ttl: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202657--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202657_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing event_ttl
NOTICE:  --Relation fsm_errors--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202449--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202449_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing fsm_errors
NOTICE:  --Relation cur_stat--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202662--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202662_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing cur_stat
NOTICE:  --Relation fsm_modules--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202454--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202454_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing fsm_modules
NOTICE:  --Relation cb_groups--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 3: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 64, MaxLen 72;
Re-using:Free/Avail. Space 7960/7960; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel cb_groups: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202667--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202667_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing cb_groups
NOTICE:  --Relation isdn_cause--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202672--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202672_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing isdn_cause
NOTICE:  --Relation fsm_modules_body--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202459--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202459_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing fsm_modules_body
NOTICE:  --Relation fixed_phones--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202677--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202677_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing fixed_phones
NOTICE:  --Relation pere1--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pere1
NOTICE:  --Relation fsm_modules_chunk--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202464--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202464_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing fsm_modules_chunk
NOTICE:  --Relation p3--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing p3
NOTICE:  --Relation lines_backup--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202686--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202686_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing lines_backup
NOTICE:  --Relation k_s--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202469--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202469_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing k_s
NOTICE:  --Relation tmp_recalc--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202599--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202599_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing tmp_recalc
NOTICE:  --Relation tmp_r1--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202604--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202604_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing tmp_r1
NOTICE:  --Relation val_types--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 3: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 48, MaxLen 48;
Re-using:Free/Avail. Space 8016/8016; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel val_types: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202474--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202474_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing val_types
NOTICE:  --Relation tmp_r3--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202609--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202609_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing tmp_r3
NOTICE:  --Relation tmp_r4_costs--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing tmp_r4_costs
NOTICE:  --Relation tmp_recalc_2--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing tmp_recalc_2
NOTICE:  --Relation tmp_r_3--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing tmp_r_3
NOTICE:  --Relation todo--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202620--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202620_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing todo
NOTICE:  --Relation gt_calls--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202625--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202625_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing gt_calls
NOTICE:  --Relation options--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202630--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202630_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing options
NOTICE:  --Relation card_states--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202484--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202484_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing card_states
NOTICE:  --Relation option_values--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202635--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202635_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing option_values
NOTICE:  --Relation plans4user--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing plans4user
NOTICE:  --Relation last_notify--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202642--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202642_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing last_notify
NOTICE:  --Relation cb_calls--
NOTICE:  Pages 170: Changed 0, reaped 0, Empty 0, New 0; Tup 11183: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 96, MaxLen
132;Re-using: Free/Avail. Space 15216/8216; EndEmpty/Avail. Pages 0/15. 
    CPU 0.01s/0.00u sec elapsed 0.01 sec.
NOTICE:  Rel cb_calls: Pages: 170 --> 170; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202489--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202489_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing cb_calls
NOTICE:  --Relation pga_queries--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202564--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202564_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pga_queries
NOTICE:  --Relation pga_forms--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202569--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202569_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pga_forms
NOTICE:  --Relation contact_types--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202494--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202494_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing contact_types
NOTICE:  --Relation pga_scripts--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202574--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202574_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pga_scripts
NOTICE:  --Relation pga_reports--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202579--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202579_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pga_reports
NOTICE:  --Relation contacts--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202499--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202499_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing contacts
NOTICE:  --Relation pga_schema--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202584--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202584_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pga_schema
NOTICE:  --Relation pga_layout--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202589--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202589_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pga_layout
NOTICE:  --Relation errors--
NOTICE:  Pages 35: Changed 0, reaped 0, Empty 0, New 0; Tup 3279: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 74, MaxLen 103;
Re-using:Free/Avail. Space 8344/7292; EndEmpty/Avail. Pages 0/4. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel errors: Pages: 35 --> 35; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202524--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202524_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing errors
NOTICE:  --Relation contact_info--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202504--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202504_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing contact_info
NOTICE:  --Relation auth_ani--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202529--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202529_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing auth_ani
NOTICE:  --Relation pbx_dnis--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202534--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202534_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pbx_dnis
NOTICE:  --Relation speed_dials--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202509--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202509_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing speed_dials
NOTICE:  --Relation pbx_dest--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202539--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202539_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing pbx_dest
NOTICE:  --Relation gt_country--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202514--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202514_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing gt_country
NOTICE:  --Relation area_realms--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202549--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202549_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing area_realms
NOTICE:  --Relation dnis--
NOTICE:  Pages 1: Changed 0, reaped 0, Empty 0, New 0; Tup 10: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 82, MaxLen 154;
Re-using:Free/Avail. Space 6892/6892; EndEmpty/Avail. Pages 0/1. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Rel dnis: Pages: 1 --> 1; Tuple(s) moved: 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202554--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202554_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing dnis
NOTICE:  --Relation n_areas--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202519--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202519_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing n_areas
NOTICE:  --Relation tad_messages--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  --Relation pg_toast_6202559--
NOTICE:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, UnUsed 0, MinLen 0, MaxLen 0;
Re-using:Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0. 
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Index pg_toast_6202559_idx: Pages 1; Tuples 0.
    CPU 0.00s/0.00u sec elapsed 0.00 sec.
NOTICE:  Analyzing tad_messages
VACUUM

it taked:
real    0m3.083s
user    0m0.047s
sys     0m0.028s

command was:
vacuumdb -z -f -v db

(after this query takes the same very long time)

Thanks,
  Andriy.