Skip site navigation (1)
Skip section navigation (2)
Peripheral Links
Header And Logo
|
Site Navigation
Strange query duration
- From: "Fernando Hevia" <fhevia(at)ip-tel(dot)com(dot)ar>
- To: <pgsql-sql(at)postgresql(dot)org>
- Subject: Strange query duration
- Date: Tue, 22 Jul 2008 16:10:55 -0300
- Message-id: <024301c8ec2e$aae2cc20$8f01010a@iptel.com.ar> <text/plain>
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
- Follow-Ups:
- Re: Strange query duration
- From: Tom Lane
- Re: Strange query duration
- From: Richard Broersma
- Re: Strange query duration
- From: Stephan Szabo
- Re: Strange query duration
- Prev by Date: Re: How to Select a Tupl by Nearest Date
- Next by Date: Re: Strange query duration
- Previous by thread: index for group by
- Next by thread: Re: Strange query duration
- Indexes: