| From: | "Fernando Hevia" <fhevia(at)ip-tel(dot)com(dot)ar> |
|---|---|
| To: | <pgsql-sql(at)postgresql(dot)org> |
| Subject: | Strange query duration |
| Date: | 2008-07-22 19:10:55 |
| Message-ID: | 024301c8ec2e$aae2cc20$8f01010a@iptel.com.ar |
| Views: | Raw Message | Whole Thread | Download mbox | Resend email |
| Thread: | |
| Lists: | pgsql-sql |
Hi list,
I just enabled log duration in a 8.3.1 database and got puzzling
information.
I have a daemon shell-script run every 10 seconds the following:
psql -c "select f_tasador();"
The 'f_tasador' procedure is quite fast. As per log output I can see the
procedure completes its execution within one second. Nevertheless in the LOG
duration entry it shows a statement duration of over 36 secs.
¿What is going on? ¿Where come those 36 seconds from?
Regards,
Fernando
--- Postgres Log extract ---
2008-07-22 15:52:37 ART|postgres| LOG: statement: select f_tasador();
2008-07-22 15:52:37 ART|postgres| NOTICE: [3576450000] Billable account
found
2008-07-22 15:52:37 ART|postgres| NOTICE: [3576450000] Destination
3514601344 not billable
2008-07-22 15:52:37 ART|postgres| NOTICE: [3576450000] Destination
3514719096 not billable
2008-07-22 15:52:37 ART|postgres| NOTICE: [3576450000] Destination
3576421309 not billable
2008-07-22 15:52:37 ART|postgres| NOTICE: [3576450000] Destination
3514601344 not billable
2008-07-22 15:52:37 ART|postgres| NOTICE: [3576450000] Destination
3514601344 not billable
2008-07-22 15:52:37 ART|postgres| NOTICE: [3576450000] Destination
3514601344 not billable
2008-07-22 15:52:37 ART|postgres| NOTICE: [3576450002] No active billable
account found
2008-07-22 15:52:37 ART|postgres| NOTICE: [3576450008] Billable account
found
2008-07-22 15:52:37 ART|postgres| NOTICE: [3576450008] Destination
3514601344 not billable
2008-07-22 15:52:37 ART|postgres| NOTICE: [3576450008] Destination
3514601344 not billable
2008-07-22 15:52:37 ART|postgres| NOTICE: [3576450008] Destination
3514601344 not billable
2008-07-22 15:52:37 ART|postgres| NOTICE: [3576450008] Destination
3514601344 not billable
2008-07-22 15:52:37 ART|postgres| NOTICE: [3576450008] Destination
3514601344 not billable
2008-07-22 15:52:37 ART|postgres| NOTICE: [3576450008] Destination
3514601344 not billable
2008-07-22 15:52:37 ART|postgres| NOTICE: [3576450009] Billable account
found
2008-07-22 15:52:37 ART|postgres| NOTICE: [3576450009] Destination
3516009059 not billable
2008-07-22 15:52:37 ART|postgres| LOG: duration: 38.154 ms
2008-07-22 15:52:47 ART|postgres| LOG: statement: select f_tasador();
2008-07-22 15:52:47 ART|postgres| NOTICE: [3576450000] Billable account
found
2008-07-22 15:52:47 ART|postgres| NOTICE: [3576450000] Destination
3514601344 not billable
2008-07-22 15:52:47 ART|postgres| NOTICE: [3576450000] Destination
3514719096 not billable
2008-07-22 15:52:47 ART|postgres| NOTICE: [3576450000] Destination
3576421309 not billable
2008-07-22 15:52:47 ART|postgres| NOTICE: [3576450000] Destination
3514601344 not billable
2008-07-22 15:52:47 ART|postgres| NOTICE: [3576450000] Destination
3514601344 not billable
2008-07-22 15:52:47 ART|postgres| NOTICE: [3576450000] Destination
3514601344 not billable
2008-07-22 15:52:47 ART|postgres| NOTICE: [3576450002] No active billable
account found
2008-07-22 15:52:47 ART|postgres| NOTICE: [3576450008] Billable account
found
2008-07-22 15:52:47 ART|postgres| NOTICE: [3576450008] Destination
3514601344 not billable
2008-07-22 15:52:47 ART|postgres| NOTICE: [3576450008] Destination
3514601344 not billable
2008-07-22 15:52:47 ART|postgres| NOTICE: [3576450008] Destination
3514601344 not billable
2008-07-22 15:52:47 ART|postgres| NOTICE: [3576450008] Destination
3514601344 not billable
2008-07-22 15:52:47 ART|postgres| NOTICE: [3576450008] Destination
3514601344 not billable
2008-07-22 15:52:47 ART|postgres| NOTICE: [3576450008] Destination
3514601344 not billable
2008-07-22 15:52:47 ART|postgres| NOTICE: [3576450009] Billable account
found
2008-07-22 15:52:47 ART|postgres| NOTICE: [3576450009] Destination
3516009059 not billable
2008-07-22 15:52:47 ART|postgres| LOG: duration: 36.781 ms
| From | Date | Subject | |
|---|---|---|---|
| Next Message | Stephan Szabo | 2008-07-22 20:18:16 | Re: Strange query duration |
| Previous Message | Christian Kindler | 2008-07-22 18:12:56 | Re: How to Select a Tupl by Nearest Date |