Re: [RFC] Add jit deform_counter - Mailing list pgsql-hackers
| From | Pavel Stehule |
|---|---|
| Subject | Re: [RFC] Add jit deform_counter |
| Date | |
| Msg-id | CAFj8pRD44r8-6C9HQDuDSkNGw49pseh8RvT_8G+8OQN7xGoYAw@mail.gmail.com Whole thread Raw |
| In response to | Re: [RFC] Add jit deform_counter (Pavel Stehule <pavel.stehule@gmail.com>) |
| Responses |
Re: [RFC] Add jit deform_counter
|
| List | pgsql-hackers |
Hi
> > I'm not sure why, but pgss jit metrics are always nulls for explain
> > analyze queries. I have noticed this with surprise myself, when recently
> > was reviewing the lazy jit patch, but haven't yet figure out what is the
> > reason. Anyway, without "explain analyze" you'll get correct deforming
> > numbers in pgss.
> >
It is working although I am not sure if it is correctly
when I run EXPLAIN ANALYZE for query `explain analyze select count(length(prosrc) > 0) from pg_proc;`
I got plan and times
┌─────────────────────────────────────────────────────────────────────────────────────────────────────────────────────────────────────┐
│ QUERY PLAN │
╞═════════════════════════════════════════════════════════════════════════════════════════════════════════════════════════════════════╡
│ Aggregate (cost=154.10..154.11 rows=1 width=8) (actual time=134.450..134.451 rows=1 loops=1) │
│ -> Seq Scan on pg_proc (cost=0.00..129.63 rows=3263 width=16) (actual time=0.013..0.287 rows=3266 loops=1) │
│ Planning Time: 0.088 ms │
│ JIT: │
│ Functions: 3 │
│ Options: Inlining true, Optimization true, Expressions true, Deforming true │
│ Timing: Generation 0.631 ms, Deforming 0.396 ms, Inlining 10.026 ms, Optimization 78.608 ms, Emission 44.915 ms, Total 134.181 ms │
│ Execution Time: 135.173 ms │
└─────────────────────────────────────────────────────────────────────────────────────────────────────────────────────────────────────┘
(8 rows)
│ QUERY PLAN │
╞═════════════════════════════════════════════════════════════════════════════════════════════════════════════════════════════════════╡
│ Aggregate (cost=154.10..154.11 rows=1 width=8) (actual time=134.450..134.451 rows=1 loops=1) │
│ -> Seq Scan on pg_proc (cost=0.00..129.63 rows=3263 width=16) (actual time=0.013..0.287 rows=3266 loops=1) │
│ Planning Time: 0.088 ms │
│ JIT: │
│ Functions: 3 │
│ Options: Inlining true, Optimization true, Expressions true, Deforming true │
│ Timing: Generation 0.631 ms, Deforming 0.396 ms, Inlining 10.026 ms, Optimization 78.608 ms, Emission 44.915 ms, Total 134.181 ms │
│ Execution Time: 135.173 ms │
└─────────────────────────────────────────────────────────────────────────────────────────────────────────────────────────────────────┘
(8 rows)
Deforming is 0.396ms
When I run mentioned query, and when I look to pg_stat_statements table, I see different times
deforming is about 10ms
wal_bytes │ 0
jit_functions │ 9
jit_generation_time │ 1.9040409999999999
jit_deform_count │ 3
jit_deform_time │ 36.395131
jit_inlining_count │ 3
jit_inlining_time │ 256.104205
jit_optimization_count │ 3
jit_optimization_time │ 132.45361300000002
jit_emission_count │ 3
jit_emission_time │ 1.210633
jit_functions │ 9
jit_generation_time │ 1.9040409999999999
jit_deform_count │ 3
jit_deform_time │ 36.395131
jit_inlining_count │ 3
jit_inlining_time │ 256.104205
jit_optimization_count │ 3
jit_optimization_time │ 132.45361300000002
jit_emission_count │ 3
jit_emission_time │ 1.210633
counts are correct, but times are strange - there is not consistency with values from EXPLAIN
When I run this query on master, the values are correct
jit_functions │ 6
jit_generation_time │ 1.350521
jit_inlining_count │ 2
jit_inlining_time │ 24.018382000000003
jit_optimization_count │ 2
jit_optimization_time │ 173.405792
jit_emission_count │ 2
jit_emission_time │ 91.226655
────────────────────────┴───────────────────
jit_generation_time │ 1.350521
jit_inlining_count │ 2
jit_inlining_time │ 24.018382000000003
jit_optimization_count │ 2
jit_optimization_time │ 173.405792
jit_emission_count │ 2
jit_emission_time │ 91.226655
────────────────────────┴───────────────────
│ JIT: │
│ Functions: 3 │
│ Options: Inlining true, Optimization true, Expressions true, Deforming true │
│ Timing: Generation 0.636 ms, Inlining 9.309 ms, Optimization 89.653 ms, Emission 45.812 ms, Total 145.410 ms │
│ Execution Time: 146.410 ms │
└────────────────────────────────────────────────────────────────────────────────────────────────────────────────┘
│ Functions: 3 │
│ Options: Inlining true, Optimization true, Expressions true, Deforming true │
│ Timing: Generation 0.636 ms, Inlining 9.309 ms, Optimization 89.653 ms, Emission 45.812 ms, Total 145.410 ms │
│ Execution Time: 146.410 ms │
└────────────────────────────────────────────────────────────────────────────────────────────────────────────────┘
Regards
Pavel
pgsql-hackers by date: