Strange query duration - Mailing list pgsql-sql
| From | Fernando Hevia |
|---|---|
| Subject | Strange query duration |
| Date | |
| Msg-id | 024301c8ec2e$aae2cc20$8f01010a@iptel.com.ar Whole thread Raw |
| Responses |
Re: Strange query duration
Re: Strange query duration Re: Strange query duration |
| List | 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