Re: Big Performance drop of Exceptions in UDFs between V11.2 and 13.4

[Ranier Vilela <ranier.vf@xxxxxxxxx>]

Em dom., 22 de ago. de 2021 às 12:48, Justin Pryzby <pryzby@xxxxxxxxxxxxx> escreveu:

On Sun, Aug 22, 2021 at 10:50:47AM -0300, Ranier Vilela wrote:
> > Tried to check this with Very Sleepy at Windows 10 (bare metal).
> > Not sure it can help if someone can guide how to test this better?

> explain (analyze,buffers,COSTS,TIMING) select MAX(toFloat(a, null)) as "a" from sampletest;

Your 100sec result *seems* to reproduce the problem, but it'd be more clear if
you showed the results of both queries (toFloat(a) vs toFloat(b)).
Laurent's queries took 800sec vs 2sec.

Not, in this test is only with toFloat(a).

> postgres.png (print screen from Very Sleepy)
> postgres.csv

This looks useful, thanks.  It seems like maybe win64 builds are very slow
running this:

exec_stmt_block() /
BeginInternalSubTransaction() /
AbortSubTransaction() /
reschedule_timeouts() /
schedule_alarm() /
setitimer() /
pg_timer_thread() /
WaitForSingleObjectEx ()

Now, test with toFloat(b):

postgres=# explain (analyze,buffers,COSTS,TIMING) select MAX(toFloat(b, null)) as "b" from sampletest;
                                                         QUERY PLAN
----------------------------------------------------------------------------------------------------------------------------
 Aggregate  (cost=21370.00..21370.01 rows=1 width=4) (actual time=16878.424..16878.426 rows=1 loops=1)
   Buffers: shared hit=64 read=6306
   ->  Seq Scan on sampletest  (cost=0.00..16370.00 rows=1000000 width=8) (actual time=0.105..937.201 rows=1000000 loops=1)
         Buffers: shared hit=64 read=6306
 Planning Time: 0.273 ms
 Execution Time: 16878.490 ms
(6 rows)

It seems to me that in this way, exec_stmt_block() is not called.

Not sure if this really is correct. I need to choose postgres.exe to attach Very Sleepy.

Attached:

to_Float_b.png

to_Float_b.csv

regards,

Ranier Vilela

Attachment: toFloat_b.png
Description: PNG image

Name,Exclusive secs,Inclusive secs,% Exclusive,% Inclusive,Module,Source File,Source Line
"plpgsql_compile",0.0519409,0.413899,0.0362765,0.289075,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_comp.c",137
"MemoryContextSwitchTo",0.0258028,0.0258028,0.0180212,0.0180212,"plpgsql","C:\dll\postgres\postgres\src\include\utils\palloc.h",110
"ExecEvalExpr",0.0066564,4.22365,0.00464896,2.94988,"plpgsql","C:\dll\postgres\postgres\src\include\executor\executor.h",319
"plpgsql_exec_function",0.129598,13.1808,0.0905135,9.20571,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_exec.c",475
"plpgsql_subxact_cb",0.0474709,0.608097,0.0331546,0.424706,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_exec.c",8360
"copy_plpgsql_datums",0.164016,0.480808,0.114552,0.335805,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_exec.c",1274
"get_stmt_mcontext",0,0.113593,0,0.0793354,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_exec.c",1510
"exec_toplevel_block",0.0073513,11.1147,0.00513429,7.76269,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_exec.c",1600
"exec_stmt_block",0.107536,11.1073,0.0751053,7.75756,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_exec.c",1635
"exec_stmts",0.134582,4.62607,0.0939948,3.23094,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_exec.c",1952
"exec_stmt_return",0.0291597,4.49149,0.0203657,3.13694,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_exec.c",3138
"plpgsql_estate_setup",0.154373,1.01786,0.107817,0.710895,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_exec.c",3940
"exec_eval_simple_expr",0.118971,4.45622,0.0830917,3.11231,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_exec.c",5960
"exec_eval_expr",0.0061089,4.46233,0.00426657,3.11658,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_exec.c",5636
"plpgsql_param_eval_var_ro",0.0207411,0.047733,0.014486,0.0333376,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_exec.c",6467
"exec_cast_value",0.0161581,0.0161581,0.0112851,0.0112851,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_exec.c",7639
"exec_set_found",0.0065246,0.0133901,0.0045569,0.0093519,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_exec.c",8213
"plpgsql_create_econtext",0.0784963,1.44242,0.0548233,1.00741,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_exec.c",8226
"plpgsql_destroy_econtext",0.0079673,0.402323,0.00556451,0.28099,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_exec.c",8303
"assign_simple_var",0.0128478,0.0128478,0.00897315,0.00897315,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_exec.c",8401
"plpgsql_call_handler",0.0389785,14.5931,0.0272233,10.1921,"plpgsql","C:\dll\postgres\postgres\src\pl\plpgsql\src\pl_handler.c",223
"palloc",0.0131301,0.0131301,0.00917031,0.00917031,"plpgsql","[unknown]",0
"ReleaseSysCache",0.0139731,0.0139731,0.00975908,0.00975908,"plpgsql","[unknown]",0
"CreateExprContext",0.0129135,0.0129135,0.00901903,0.00901903,"plpgsql","[unknown]",0
"EnsurePortalSnapshotExists",0.007946,0.007946,0.00554964,0.00554964,"plpgsql","[unknown]",0
"datumTransfer",0.0085281,0.0085281,0.00595619,0.00595619,"plpgsql","[unknown]",0
"get_typlenbyval",0.0062449,0.0062449,0.00436156,0.00436156,"plpgsql","[unknown]",0
"ss_report_location",0,0.005826,0,0.00406899,"postgres","C:\dll\postgres\postgres\src\backend\access\common\syncscan.c",310
"heapgetpage",0.0492761,0.428406,0.0344154,0.299207,"postgres","C:\dll\postgres\postgres\src\backend\access\heap\heapam.c",400
"heap_getnextslot",0.0148337,0.70233,0.0103601,0.49052,"postgres","C:\dll\postgres\postgres\src\backend\access\heap\heapam.c",1396
"heapgettup_pagemode",0.0954508,0.529682,0.0666646,0.36994,"postgres","C:\dll\postgres\postgres\src\backend\access\heap\heapam.c",906
"MemoryContextSwitchTo",0.140271,0.140271,0.0979679,0.0979679,"postgres","C:\dll\postgres\postgres\src\include\utils\palloc.h",110
"FullTransactionIdFromEpochAndXid",0.0067256,0.0067256,0.00469729,0.00469729,"postgres","C:\dll\postgres\postgres\src\include\access\transam.h",72
"GetCurrentSubTransactionId",0.0237964,0.0237964,0.0166199,0.0166199,"postgres","C:\dll\postgres\postgres\src\backend\access\transam\xact.c",724
"GetCurrentTransactionNestLevel",0.0881166,0.0881166,0.0615423,0.0615423,"postgres","C:\dll\postgres\postgres\src\backend\access\transam\xact.c",858
"CommandCounterIncrement",0.006979,0.006979,0.00487427,0.00487427,"postgres","C:\dll\postgres\postgres\src\backend\access\transam\xact.c",1029
"StartTransactionCommand",0.0196929,0.0196929,0.0137539,0.0137539,"postgres","C:\dll\postgres\postgres\src\backend\access\transam\xact.c",2842
"CommitTransactionCommand",0.0609273,1.93135,0.0425528,1.34889,"postgres","C:\dll\postgres\postgres\src\backend\access\transam\xact.c",2943
"BeginInternalSubTransaction",0.0273872,2.3746,0.0191277,1.65847,"postgres","C:\dll\postgres\postgres\src\backend\access\transam\xact.c",4409
"ReleaseCurrentSubTransaction",0.0209263,2.50531,0.0146153,1.74976,"postgres","C:\dll\postgres\postgres\src\backend\access\transam\xact.c",4489
"IsInParallelMode",0.0289656,0.0289656,0.0202301,0.0202301,"postgres","C:\dll\postgres\postgres\src\backend\access\transam\xact.c",1014
"CallSubXactCallbacks",0.0565117,0.664608,0.0394688,0.464175,"postgres","C:\dll\postgres\postgres\src\backend\access\transam\xact.c",3622
"StartSubTransaction",0.0199307,1.87042,0.01392,1.30634,"postgres","C:\dll\postgres\postgres\src\backend\access\transam\xact.c",4783
"CommitSubTransaction",0.162224,2.44351,0.113301,1.70659,"postgres","C:\dll\postgres\postgres\src\backend\access\transam\xact.c",4822
"PushTransaction",0.0278131,0.396172,0.0194252,0.276694,"postgres","C:\dll\postgres\postgres\src\backend\access\transam\xact.c",5119
"PopTransaction",0.0345224,0.133459,0.0241111,0.09321,"postgres","C:\dll\postgres\postgres\src\backend\access\transam\xact.c",5179
"AtSubCommit_Memory",0.0080449,0.0577578,0.00561871,0.0403391,"postgres","C:\dll\postgres\postgres\src\backend\access\transam\xact.c",1538
"AtSubStart_Memory",0.0060039,0.0900418,0.00419324,0.0628869,"postgres","C:\dll\postgres\postgres\src\backend\access\transam\xact.c",1181
"AtSubStart_ResourceOwner",0.0196418,1.58457,0.0137182,1.10669,"postgres","C:\dll\postgres\postgres\src\backend\access\transam\xact.c",1195
"ShowTransactionState",0,0.0869597,0,0.0607343,"postgres","C:\dll\postgres\postgres\src\backend\access\transam\xact.c",5343
"OverrideSearchPathMatchesCurrent",0.0323477,0.0935248,0.0225922,0.0653195,"postgres","C:\dll\postgres\postgres\src\backend\catalog\namespace.c",3465
"AtEOSubXact_Namespace",0.0339179,0.0339179,0.0236889,0.0236889,"postgres","C:\dll\postgres\postgres\src\backend\catalog\namespace.c",4179
"recomputeNamespacePath",0.0414445,0.0611771,0.0289456,0.0427272,"postgres","C:\dll\postgres\postgres\src\backend\catalog\namespace.c",3783
"AtSubCommit_smgr",0.0130485,0.0593211,0.00911332,0.041431,"postgres","C:\dll\postgres\postgres\src\backend\catalog\storage.c",896
"AtSubCommit_Notify",0.0145732,0.0284723,0.0101782,0.0198856,"postgres","C:\dll\postgres\postgres\src\backend\commands\async.c",1777
"ExplainQuery",0,16.8788,0,11.7885,"postgres","C:\dll\postgres\postgres\src\backend\commands\explain.c",286
"ExplainOnePlan",0,16.8788,0,11.7885,"postgres","C:\dll\postgres\postgres\src\backend\commands\explain.c",596
"ExplainOneQuery",0,16.8788,0,11.7885,"postgres","C:\dll\postgres\postgres\src\backend\commands\explain.c",413
"AtEOSubXact_on_commit_actions",0.012945,0.012945,0.00904103,0.00904103,"postgres","C:\dll\postgres\postgres\src\backend\commands\tablecmds.c",16391
"AfterTriggerBeginSubXact",0.0589984,0.0729324,0.0412056,0.0509374,"postgres","C:\dll\postgres\postgres\src\backend\commands\trigger.c",4941
"AfterTriggerEndSubXact",0.0475215,0.0615324,0.0331899,0.0429754,"postgres","C:\dll\postgres\postgres\src\backend\commands\trigger.c",4989
"slot_getsomeattrs",0,0.269842,0,0.188463,"postgres","C:\dll\postgres\postgres\src\include\executor\tuptable.h",345
"ExecInterpExpr",0.654232,15.3361,0.456928,10.711,"postgres","C:\dll\postgres\postgres\src\backend\executor\execExprInterp.c",391
"ExecAggPlainTransByVal",0.0059794,0.0739264,0.00417613,0.0516316,"postgres","C:\dll\postgres\postgres\src\backend\executor\execExprInterp.c",4325
"ExecutorRun",0,16.8788,0,11.7885,"postgres","C:\dll\postgres\postgres\src\backend\executor\execMain.c",306
"standard_ExecutorRun",0,16.8788,0,11.7885,"postgres","C:\dll\postgres\postgres\src\backend\executor\execMain.c",375
"ExecProcNode",0.0074373,16.8788,0.00519435,11.7885,"postgres","C:\dll\postgres\postgres\src\include\executor\executor.h",258
"ExecutePlan",0,16.8788,0,11.7885,"postgres","C:\dll\postgres\postgres\src\backend\executor\execMain.c",1551
"ExecProcNodeFirst",0,16.8788,0,11.7885,"postgres","C:\dll\postgres\postgres\src\backend\executor\execProcnode.c",464
"ExecProcNodeInstr",0.0079681,16.8788,0.00556507,11.7885,"postgres","C:\dll\postgres\postgres\src\backend\executor\execProcnode.c",479
"ExecScan",0.0323781,0.842557,0.0226135,0.588457,"postgres","C:\dll\postgres\postgres\src\backend\executor\execScan.c",170
"ExecScanFetch",0.007085,0.77679,0.0049483,0.542525,"postgres","C:\dll\postgres\postgres\src\backend\executor\execScan.c",41
"ExecStoreBufferHeapTuple",0.0348526,0.157814,0.0243417,0.11022,"postgres","C:\dll\postgres\postgres\src\backend\executor\execTuples.c",1408
"slot_getsomeattrs_int",0.0197111,0.269842,0.0137666,0.188463,"postgres","C:\dll\postgres\postgres\src\backend\executor\execTuples.c",1903
"slot_deform_heap_tuple",0.223564,0.223564,0.156141,0.156141,"postgres","C:\dll\postgres\postgres\src\backend\executor\execTuples.c",928
"tts_buffer_heap_store_tuple",0.122961,0.122961,0.0858784,0.0858784,"postgres","C:\dll\postgres\postgres\src\backend\executor\execTuples.c",861
"tts_buffer_heap_getsomeattrs",0.0265672,0.250131,0.018555,0.174696,"postgres","C:\dll\postgres\postgres\src\backend\executor\execTuples.c",690
"CreateExprContext",0.0063945,1.14644,0.00446604,0.800698,"postgres","C:\dll\postgres\postgres\src\backend\executor\execUtils.c",302
"FreeExprContext",0.0418322,0.801536,0.0292164,0.559808,"postgres","C:\dll\postgres\postgres\src\backend\executor\execUtils.c",412
"ShutdownExprContext",0.0202018,0.0202018,0.0141093,0.0141093,"postgres","C:\dll\postgres\postgres\src\backend\executor\execUtils.c",988
"CreateExprContextInternal",0.0870011,1.14005,0.0607632,0.796231,"postgres","C:\dll\postgres\postgres\src\backend\executor\execUtils.c",233
"InstrStartNode",0.0784562,0.219119,0.0547953,0.153037,"postgres","C:\dll\postgres\postgres\src\backend\executor\instrument.c",69
"InstrStopNode",0.0224055,0.247201,0.0156484,0.17265,"postgres","C:\dll\postgres\postgres\src\backend\executor\instrument.c",90
"BufferUsageAccumDiff",0.118535,0.118535,0.0827874,0.0827874,"postgres","C:\dll\postgres\postgres\src\backend\executor\instrument.c",248
"ExecEvalExprSwitchContext",0.0124508,15.3952,0.00869587,10.7523,"postgres","C:\dll\postgres\postgres\src\include\executor\executor.h",338
"fetch_input_tuple",0.021231,1.3722,0.0148281,0.958371,"postgres","C:\dll\postgres\postgres\src\backend\executor\nodeAgg.c",569
"advance_aggregates",0.0131325,15.4083,0.00917199,10.7615,"postgres","C:\dll\postgres\postgres\src\backend\executor\nodeAgg.c",840
"agg_retrieve_direct",0.0771857,16.8788,0.053908,11.7885,"postgres","C:\dll\postgres\postgres\src\backend\executor\nodeAgg.c",2448
"ExecAgg",0,16.8788,0,11.7885,"postgres","C:\dll\postgres\postgres\src\backend\executor\nodeAgg.c",2180
"table_scan_getnextslot",0.0479115,0.750241,0.0334623,0.523983,"postgres","C:\dll\postgres\postgres\src\include\access\tableam.h",1034
"SeqNext",0.0194637,0.769705,0.0135938,0.537577,"postgres","C:\dll\postgres\postgres\src\backend\executor\nodeSeqscan.c",51
"ExecSeqScan",0.0266875,0.869244,0.0186391,0.607097,"postgres","C:\dll\postgres\postgres\src\backend\executor\nodeSeqscan.c",109
"slist_init",0.0070443,0.0070443,0.00491987,0.00491987,"postgres","C:\dll\postgres\postgres\src\include\lib\ilist.h",574
"SPI_connect_ext",0.0878282,0.268998,0.0613409,0.187873,"postgres","C:\dll\postgres\postgres\src\backend\executor\spi.c",109
"SPI_finish",0.0536398,0.646488,0.0374631,0.45152,"postgres","C:\dll\postgres\postgres\src\backend\executor\spi.c",186
"AtEOSubXact_SPI",0.0402315,0.0402315,0.0280984,0.0280984,"postgres","C:\dll\postgres\postgres\src\backend\executor\spi.c",394
"_SPI_begin_call",0.007005,0.007005,0.00489242,0.00489242,"postgres","C:\dll\postgres\postgres\src\backend\executor\spi.c",2904
"AtEOSubXact_LargeObject",0.0064727,0.0064727,0.00452066,0.00452066,"postgres","C:\dll\postgres\postgres\src\backend\libpq\be-fsstubs.c",634
"secure_read",0,6.98464,0,4.8782,"postgres","C:\dll\postgres\postgres\src\backend\libpq\be-secure.c",206
"pq_getbyte",0,6.98464,0,4.8782,"postgres","C:\dll\postgres\postgres\src\backend\libpq\pqcomm.c",1000
"pq_recvbuf",0,6.98464,0,4.8782,"postgres","C:\dll\postgres\postgres\src\backend\libpq\pqcomm.c",957
"main",0,23.8634,0,16.6667,"postgres","C:\dll\postgres\postgres\src\backend\main\main.c",190
"list_nth_cell",0.0486164,0.0486164,0.0339546,0.0339546,"postgres","C:\dll\postgres\postgres\src\include\nodes\pg_list.h",257
"lcons",0.0303416,0.21798,0.0211911,0.152241,"postgres","C:\dll\postgres\postgres\src\backend\nodes\list.c",473
"list_delete_ptr",0.0276798,0.247522,0.0193321,0.172874,"postgres","C:\dll\postgres\postgres\src\backend\nodes\list.c",812
"list_delete_nth_cell",0.0191347,0.193405,0.013364,0.135078,"postgres","C:\dll\postgres\postgres\src\backend\nodes\list.c",722
"list_delete_cell",0.0264367,0.219842,0.0184639,0.153542,"postgres","C:\dll\postgres\postgres\src\backend\nodes\list.c",783
"list_free",0,0.116631,0,0.0814574,"postgres","C:\dll\postgres\postgres\src\backend\nodes\list.c",1394
"pg_nextpower2_32",0.0139443,0.0139443,0.00973896,0.00973896,"postgres","C:\dll\postgres\postgres\src\include\port\pg_bitutils.h",155
"new_list",0.0404982,0.0889758,0.0282847,0.0621424,"postgres","C:\dll\postgres\postgres\src\backend\nodes\list.c",123
"new_head_cell",0.0360845,0.0500461,0.0252021,0.0349531,"postgres","C:\dll\postgres\postgres\src\backend\nodes\list.c",305
"list_free_private",0.027469,0.116631,0.0191849,0.0814574,"postgres","C:\dll\postgres\postgres\src\backend\nodes\list.c",1366
"makeParamList",0.0286411,0.124585,0.0200035,0.0870127,"postgres","C:\dll\postgres\postgres\src\backend\nodes\params.c",45
"pg_signal_thread",0,23.8634,0,16.6667,"postgres","C:\dll\postgres\postgres\src\backend\port\win32\signal.c",262
"pg_timer_thread",0,23.8634,0,16.6667,"postgres","C:\dll\postgres\postgres\src\backend\port\win32\timer.c",48
"AtEOSubXact_PgStat",0.0196961,0.0196961,0.0137561,0.0137561,"postgres","C:\dll\postgres\postgres\src\backend\postmaster\pgstat.c",2423
"SubPostmasterMain",0,23.8634,0,16.6667,"postgres","C:\dll\postgres\postgres\src\backend\postmaster\postmaster.c",5020
"BackendRun",0,23.8634,0,16.6667,"postgres","C:\dll\postgres\postgres\src\backend\postmaster\postmaster.c",4515
"BufTableInsert",0,0.0070371,0,0.00491484,"postgres","C:\dll\postgres\postgres\src\backend\storage\buffer\buf_table.c",127
"BufTableDelete",0,0.0079436,0,0.00554796,"postgres","C:\dll\postgres\postgres\src\backend\storage\buffer\buf_table.c",153
"init_spin_delay",0.0059821,0.0059821,0.00417801,0.00417801,"postgres","C:\dll\postgres\postgres\src\include\storage\s_lock.h",1061
"ReadBufferExtended",0,0.371387,0,0.259384,"postgres","C:\dll\postgres\postgres\src\backend\storage\buffer\bufmgr.c",762
"LockBuffer",0,0.0077421,0,0.00540723,"postgres","C:\dll\postgres\postgres\src\backend\storage\buffer\bufmgr.c",4009
"LockBufHdr",0,0.0059821,0,0.00417801,"postgres","C:\dll\postgres\postgres\src\backend\storage\buffer\bufmgr.c",4585
"ReadBuffer_common",0,0.371387,0,0.259384,"postgres","C:\dll\postgres\postgres\src\backend\storage\buffer\bufmgr.c",846
"BufferAlloc",0,0.026959,0,0.0188287,"postgres","C:\dll\postgres\postgres\src\backend\storage\buffer\bufmgr.c",1170
"StrategyGetBuffer",0,0.0059821,0,0.00417801,"postgres","C:\dll\postgres\postgres\src\backend\storage\buffer\freelist.c",214
"GetBufferFromRing",0,0.0059821,0,0.00417801,"postgres","C:\dll\postgres\postgres\src\backend\storage\buffer\freelist.c",644
"FileRead",0.0082894,0.344428,0.00578947,0.240555,"postgres","C:\dll\postgres\postgres\src\backend\storage\file\fd.c",2129
"AtEOSubXact_Files",0.0195375,0.0195375,0.0136454,0.0136454,"postgres","C:\dll\postgres\postgres\src\backend\storage\file\fd.c",3008
"WaitEventSetWait",0,6.98464,0,4.8782,"postgres","C:\dll\postgres\postgres\src\backend\storage\ipc\latch.c",1396
"WaitEventSetWaitBlock",0,6.98464,0,4.8782,"postgres","C:\dll\postgres\postgres\src\backend\storage\ipc\latch.c",1892
"LockReassignCurrentOwner",0.0271803,0.0349346,0.0189832,0.024399,"postgres","C:\dll\postgres\postgres\src\backend\storage\lmgr\lock.c",2580
"pg_atomic_compare_exchange_u32_impl",0.005826,0.005826,0.00406899,0.00406899,"postgres","C:\dll\postgres\postgres\src\include\port\atomics\generic-msvc.h",55
"pg_atomic_fetch_sub_u32_impl",0.0059962,0.0059962,0.00418786,0.00418786,"postgres","C:\dll\postgres\postgres\src\include\port\atomics\generic.h",198
"pg_atomic_sub_fetch_u32_impl",0,0.0059962,0,0.00418786,"postgres","C:\dll\postgres\postgres\src\include\port\atomics\generic.h",242
"pg_atomic_compare_exchange_u32",0,0.005826,0,0.00406899,"postgres","C:\dll\postgres\postgres\src\include\port\atomics.h",318
"pg_atomic_sub_fetch_u32",0,0.0059962,0,0.00418786,"postgres","C:\dll\postgres\postgres\src\include\port\atomics.h",406
"LWLockConditionalAcquire",0,0.005826,0,0.00406899,"postgres","C:\dll\postgres\postgres\src\backend\storage\lmgr\lwlock.c",1390
"LWLockRelease",0.0077421,0.0137383,0.00540723,0.00959509,"postgres","C:\dll\postgres\postgres\src\backend\storage\lmgr\lwlock.c",1836
"LWLockAttemptLock",0,0.005826,0,0.00406899,"postgres","C:\dll\postgres\postgres\src\backend\storage\lmgr\lwlock.c",860
"ItemPointerEquals",0.0130439,0.0130439,0.00911011,0.00911011,"postgres","C:\dll\postgres\postgres\src\backend\storage\page\itemptr.c",39
"mdread",0,0.344428,0,0.240555,"postgres","C:\dll\postgres\postgres\src\backend\storage\smgr\md.c",652
"smgrread",0,0.344428,0,0.240555,"postgres","C:\dll\postgres\postgres\src\backend\storage\smgr\smgr.c",505
"PostgresMain",0,23.8634,0,16.6667,"postgres","C:\dll\postgres\postgres\src\backend\tcop\postgres.c",4420
"SocketBackend",0,6.98464,0,4.8782,"postgres","C:\dll\postgres\postgres\src\backend\tcop\postgres.c",353
"ReadCommand",0,6.98464,0,4.8782,"postgres","C:\dll\postgres\postgres\src\backend\tcop\postgres.c",476
"exec_simple_query",0,16.8788,0,11.7885,"postgres","C:\dll\postgres\postgres\src\backend\tcop\postgres.c",1222
"PortalRun",0,16.8788,0,11.7885,"postgres","C:\dll\postgres\postgres\src\backend\tcop\pquery.c",763
"FillPortalStore",0,16.8788,0,11.7885,"postgres","C:\dll\postgres\postgres\src\backend\tcop\pquery.c",1028
"PortalRunUtility",0,16.8788,0,11.7885,"postgres","C:\dll\postgres\postgres\src\backend\tcop\pquery.c",1157
"ProcessUtility",0,16.8788,0,11.7885,"postgres","C:\dll\postgres\postgres\src\backend\tcop\utility.c",530
"standard_ProcessUtility",0,16.8788,0,11.7885,"postgres","C:\dll\postgres\postgres\src\backend\tcop\utility.c",846
"datumCopy",0.0216799,0.0216799,0.0151417,0.0151417,"postgres","C:\dll\postgres\postgres\src\backend\utils\adt\datum.c",132
"datumTransfer",0.0316799,0.0533598,0.0221258,0.0372675,"postgres","C:\dll\postgres\postgres\src\backend\utils\adt\datum.c",194
"MakeExpandedObjectReadOnlyInternal",0.0269919,0.0269919,0.0188517,0.0188517,"postgres","C:\dll\postgres\postgres\src\backend\utils\adt\expandeddatum.c",100
"Float4GetDatum",0.0187262,0.0187262,0.0130787,0.0130787,"postgres","C:\dll\postgres\postgres\src\include\postgres.h",733
"float4_gt",0.0066477,0.0268811,0.00464288,0.0187743,"postgres","C:\dll\postgres\postgres\src\include\utils\float.h",311
"float4in",0.0461764,3.78815,0.0322505,2.64571,"postgres","C:\dll\postgres\postgres\src\backend\utils\adt\float.c",164
"float4larger",0.0340826,0.067947,0.0238039,0.0474555,"postgres","C:\dll\postgres\postgres\src\backend\utils\adt\float.c",625
"varcharout",0,0.126105,0,0.0880742,"postgres","C:\dll\postgres\postgres\src\backend\utils\adt\varchar.c",515
"text_to_cstring",0.0350562,0.126105,0.0244839,0.0880742,"postgres","C:\dll\postgres\postgres\src\backend\utils\adt\varlena.c",225
"murmurhash32",0.0595819,0.0595819,0.0416131,0.0416131,"postgres","C:\dll\postgres\postgres\src\include\common\hashfn.h",94
"SearchCatCache1",0.0148873,0.683071,0.0103976,0.47707,"postgres","C:\dll\postgres\postgres\src\backend\utils\cache\catcache.c",1169
"ReleaseCatCache",0.0398585,0.111486,0.0278379,0.0778641,"postgres","C:\dll\postgres\postgres\src\backend\utils\cache\catcache.c",1444
"SearchCatCacheInternal",0.23167,0.668184,0.161803,0.466672,"postgres","C:\dll\postgres\postgres\src\backend\utils\cache\catcache.c",1222
"CatalogCacheComputeHashValue",0.0465308,0.113092,0.032498,0.0789857,"postgres","C:\dll\postgres\postgres\src\backend\utils\cache\catcache.c",272
"CatalogCacheCompareTuple",0.0404027,0.0590017,0.028218,0.0412079,"postgres","C:\dll\postgres\postgres\src\backend\utils\cache\catcache.c",377
"int4eqfast",0.018599,0.018599,0.0129899,0.0129899,"postgres","C:\dll\postgres\postgres\src\backend\utils\cache\catcache.c",160
"int4hashfast",0.0069795,0.0665614,0.00487462,0.0464877,"postgres","C:\dll\postgres\postgres\src\backend\utils\cache\catcache.c",166
"AtEOSubXact_Inval",0.0220575,0.0220575,0.0154054,0.0154054,"postgres","C:\dll\postgres\postgres\src\backend\utils\cache\inval.c",1070
"get_typlenbyval",0.033929,0.563236,0.0236967,0.393375,"postgres","C:\dll\postgres\postgres\src\backend\utils\cache\lsyscache.c",2199
"CachedPlanIsSimplyValid",0.0058265,0.0993513,0.00406934,0.0693888,"postgres","C:\dll\postgres\postgres\src\backend\utils\cache\plancache.c",1426
"AtEOSubXact_RelationCache",0.0244497,0.0244497,0.0170761,0.0170761,"postgres","C:\dll\postgres\postgres\src\backend\utils\cache\relcache.c",3225
"SearchSysCache1",0.0359149,0.718986,0.0250836,0.502153,"postgres","C:\dll\postgres\postgres\src\backend\utils\cache\syscache.c",1129
"ReleaseSysCache",0.0337761,0.145262,0.0235899,0.101454,"postgres","C:\dll\postgres\postgres\src\backend\utils\cache\syscache.c",1176
"message_level_is_interesting",0.0208788,0.0869597,0.0145821,0.0607343,"postgres","C:\dll\postgres\postgres\src\backend\utils\error\elog.c",271
"is_log_level_output",0.0120113,0.0120113,0.00838892,0.00838892,"postgres","C:\dll\postgres\postgres\src\backend\utils\error\elog.c",200
"should_output_to_server",0.0339579,0.0459692,0.0237168,0.0321058,"postgres","C:\dll\postgres\postgres\src\backend\utils\error\elog.c",234
"should_output_to_client",0.0201117,0.0201117,0.0140464,0.0140464,"postgres","C:\dll\postgres\postgres\src\backend\utils\error\elog.c",243
"pg_detoast_datum_packed",0.0125425,0.0125425,0.00875992,0.00875992,"postgres","C:\dll\postgres\postgres\src\backend\utils\fmgr\fmgr.c",1756
"hash_search_with_hash_value",0.0079436,0.0149807,0.00554796,0.0104628,"postgres","C:\dll\postgres\postgres\src\backend\utils\hash\dynahash.c",1033
"hash_seq_init",0.0411587,0.088479,0.028746,0.0617954,"postgres","C:\dll\postgres\postgres\src\backend\utils\hash\dynahash.c",1430
"hash_seq_search",0.254306,0.275685,0.177612,0.192544,"postgres","C:\dll\postgres\postgres\src\backend\utils\hash\dynahash.c",1437
"hash_seq_term",0,0.0213785,0,0.0149311,"postgres","C:\dll\postgres\postgres\src\backend\utils\hash\dynahash.c",1516
"get_hash_entry",0.0070371,0.0070371,0.00491484,0.00491484,"postgres","C:\dll\postgres\postgres\src\backend\utils\hash\dynahash.c",1298
"register_seq_scan",0.0473203,0.0473203,0.0330494,0.0330494,"postgres","C:\dll\postgres\postgres\src\backend\utils\hash\dynahash.c",1837
"deregister_seq_scan",0.0213785,0.0213785,0.0149311,0.0149311,"postgres","C:\dll\postgres\postgres\src\backend\utils\hash\dynahash.c",1853
"GetUserId",0.0197326,0.0197326,0.0137816,0.0137816,"postgres","C:\dll\postgres\postgres\src\backend\utils\init\miscinit.c",496
"GetUserIdAndSecContext",0.005983,0.005983,0.00417864,0.00417864,"postgres","C:\dll\postgres\postgres\src\backend\utils\init\miscinit.c",604
"NewGUCNestLevel",0.0071126,0.0071126,0.00496757,0.00496757,"postgres","C:\dll\postgres\postgres\src\backend\utils\misc\guc.c",6205
"AtEOXact_GUC",0.0328867,0.0328867,0.0229687,0.0229687,"postgres","C:\dll\postgres\postgres\src\backend\utils\misc\guc.c",6219
"AllocSetContextCreateInternal",0.204131,0.51871,0.142569,0.362277,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\aset.c",384
"AllocSetAlloc",0.459527,0.669887,0.320942,0.467862,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\aset.c",722
"AllocSetFree",0.239623,0.35619,0.167357,0.24877,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\aset.c",994
"AllocSetReset",0.434862,0.434862,0.303716,0.303716,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\aset.c",560
"AllocSetDelete",0.182732,0.645217,0.127624,0.450631,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\aset.c",628
"AllocSetFreeIndex",0.326926,0.326926,0.228331,0.228331,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\aset.c",310
"MemoryContextAlloc",0.0620154,0.242123,0.0433127,0.169104,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\mcxt.c",864
"MemoryContextAllocZero",1.69751,1.7996,1.18557,1.25687,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\mcxt.c",918
"MemoryContextAllocZeroAligned",0.552099,0.662426,0.385597,0.462651,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\mcxt.c",945
"palloc",0.0621853,0.33955,0.0434314,0.237148,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\mcxt.c",1063
"pfree",0.181099,0.614482,0.126483,0.429166,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\mcxt.c",1170
"MemoryContextReset",0.0544893,0.0544893,0.0380564,0.0380564,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\mcxt.c",148
"MemoryContextDelete",0.144663,0.968932,0.101035,0.67672,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\mcxt.c",219
"MemoryContextResetOnly",0.0069351,0.462484,0.00484361,0.323008,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\mcxt.c",163
"MemoryContextDeleteChildren",0,0.121202,0,0.0846498,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\mcxt.c",272
"MemoryContextSetParent",0.117044,0.117044,0.0817457,0.0817457,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\mcxt.c",362
"MemoryContextIsEmpty",0.0065877,0.0065877,0.00460097,0.00460097,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\mcxt.c",469
"GetMemoryChunkContext",0.0771923,0.0771923,0.0539126,0.0539126,"postgres","C:\dll\postgres\postgres\src\include\utils\memutils.h",115
"MemoryContextCreate",0.314579,0.314579,0.219708,0.219708,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\mcxt.c",820
"MemoryContextCallResetCallbacks",0.0826959,0.0826959,0.0577564,0.0577564,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\mcxt.c",310
"AtSubCommit_Portals",0.0664671,0.430631,0.0464219,0.300761,"postgres","C:\dll\postgres\postgres\src\backend\utils\mmgr\portalmem.c",950
"ResourceOwnerCreate",0.0342972,1.56493,0.0239538,1.09297,"postgres","C:\dll\postgres\postgres\src\backend\utils\resowner\resowner.c",432
"ResourceOwnerRelease",0.0623475,0.308368,0.0435447,0.21537,"postgres","C:\dll\postgres\postgres\src\backend\utils\resowner\resowner.c",490
"ResourceOwnerDelete",0.0732764,0.31906,0.0511776,0.222837,"postgres","C:\dll\postgres\postgres\src\backend\utils\resowner\resowner.c",738
"ResourceOwnerGetParent",0.0077543,0.0077543,0.00541575,0.00541575,"postgres","C:\dll\postgres\postgres\src\backend\utils\resowner\resowner.c",795
"ResourceOwnerNewParent",0.0500074,0.0500074,0.0349261,0.0349261,"postgres","C:\dll\postgres\postgres\src\backend\utils\resowner\resowner.c",806
"ResourceOwnerEnlargeCatCacheRefs",0.0079751,0.225689,0.00556996,0.157625,"postgres","C:\dll\postgres\postgres\src\backend\utils\resowner\resowner.c",1037
"ResourceOwnerRememberCatCacheRef",0.0069755,0.0387311,0.00487182,0.0270505,"postgres","C:\dll\postgres\postgres\src\backend\utils\resowner\resowner.c",1047
"ResourceOwnerForgetCatCacheRef",0.0133914,0.0716278,0.00935281,0.0500262,"postgres","C:\dll\postgres\postgres\src\backend\utils\resowner\resowner.c",1056
"ResourceArrayInit",0.0795708,0.0795708,0.0555738,0.0555738,"postgres","C:\dll\postgres\postgres\src\backend\utils\resowner\resowner.c",196
"ResourceArrayEnlarge",0.156362,0.217714,0.109206,0.152055,"postgres","C:\dll\postgres\postgres\src\backend\utils\resowner\resowner.c",215
"ResourceArrayAdd",0.0317556,0.0317556,0.0221787,0.0221787,"postgres","C:\dll\postgres\postgres\src\backend\utils\resowner\resowner.c",270
"ResourceArrayRemove",0.0582364,0.0582364,0.0406734,0.0406734,"postgres","C:\dll\postgres\postgres\src\backend\utils\resowner\resowner.c",316
"ResourceArrayGetAny",0.0866839,0.0866839,0.0605417,0.0605417,"postgres","C:\dll\postgres\postgres\src\backend\utils\resowner\resowner.c",377
"ResourceArrayFree",0.0267916,0.105462,0.0187118,0.0736568,"postgres","C:\dll\postgres\postgres\src\backend\utils\resowner\resowner.c",409
"ResourceOwnerReleaseInternal",0.124402,0.246021,0.0868848,0.171825,"postgres","C:\dll\postgres\postgres\src\backend\utils\resowner\resowner.c",507
"AtSubCommit_Snapshot",0.0069475,0.0069475,0.00485227,0.00485227,"postgres","C:\dll\postgres\postgres\src\backend\utils\time\snapmgr.c",957
"pg_pread",0,0.336139,0,0.234766,"postgres","C:\dll\postgres\postgres\src\port\pread.c",42
"__scrt_common_main",0,23.8634,0,16.6667,"postgres","d:\a01\_work\2\s\src\vctools\crt\vcstartup\src\startup\exe_common.inl",331
"__scrt_common_main_seh",0,23.8634,0,16.6667,"postgres","d:\a01\_work\2\s\src\vctools\crt\vcstartup\src\startup\exe_common.inl",288
"invoke_main",0,23.8634,0,16.6667,"postgres","d:\a01\_work\2\s\src\vctools\crt\vcstartup\src\startup\exe_common.inl",79
"mainCRTStartup",0,23.8634,0,16.6667,"postgres","d:\a01\_work\2\s\src\vctools\crt\vcstartup\src\startup\exe_main.cpp",17
"memmove",0.0069895,0.0069895,0.0048816,0.0048816,"postgres","[unknown]",0
"memcpy",0.0200105,0.0200105,0.0139757,0.0139757,"postgres","[unknown]",0
"cgetws",0.069652,0.667946,0.0486463,0.466506,"ucrtbased","[unknown]",0
"cputws",0.363997,0.776919,0.254222,0.542615,"ucrtbased","[unknown]",0
"_conio_common_vcwscanf",0.0401967,0.777458,0.0280741,0.542991,"ucrtbased","[unknown]",0
"putwch_nolock",0.0753978,0.574573,0.0526593,0.401292,"ucrtbased","[unknown]",0
"o_wmemmove_s",0.553774,1.37089,0.386766,0.957455,"ucrtbased","[unknown]",0
"mbtowc",0.040929,2.07278,0.0285856,1.44767,"ucrtbased","[unknown]",0
"strtof",0.026979,2.09976,0.0188426,1.46651,"ucrtbased","[unknown]",0
"ultow_s",0.148721,0.884722,0.103869,0.617907,"ucrtbased","[unknown]",0
"isspace",0.0130519,0.897774,0.00911569,0.627023,"ucrtbased","[unknown]",0
"msize",0.463037,0.854497,0.323394,0.596797,"ucrtbased","[unknown]",0
"unlock_locales",0.252882,1.45434,0.176618,1.01574,"ucrtbased","[unknown]",0
"set_app_type",0.685489,1.47105,0.478758,1.02741,"ucrtbased","[unknown]",0
"localeconv",0.0682883,0.584751,0.0476938,0.408401,"ucrtbased","[unknown]",0
"errno",0.0209103,0.732693,0.0146041,0.511727,"ucrtbased","[unknown]",0
"fdiml",0.034539,0.034539,0.0241227,0.0241227,"ucrtbased","[unknown]",0
"fegetround",0.0079782,0.331231,0.00557213,0.231338,"ucrtbased","[unknown]",0
"fesetenv",0.0272233,0.0272233,0.0190133,0.0190133,"ucrtbased","[unknown]",0
"fdclass",0.0060657,0.0202334,0.0042364,0.0141314,"ucrtbased","[unknown]",0
"truncl",0.26149,0.323252,0.18263,0.225766,"ucrtbased","[unknown]",0
"fdtest",0.0141677,0.0141677,0.00989499,0.00989499,"ucrtbased","[unknown]",0
"local_unwind",0.0568626,0.0568626,0.0397139,0.0397139,"ucrtbased","[unknown]",0
"o_memset",0.0188458,0.0188458,0.0131623,0.0131623,"ucrtbased","[unknown]",0
"_report_gsfailure",0.0059835,0.0059835,0.00417899,0.00417899,"ucrtbased","[unknown]",0
"_intrinsic_setjmpex",0.0062145,0.0062145,0.00434032,0.00434032,"ucrtbased","[unknown]",0
"memcpy",0.217697,0.217697,0.152043,0.152043,"VCRUNTIME140D","[unknown]",0
"_intrinsic_setjmp",0.0697347,0.0697347,0.048704,0.048704,"VCRUNTIME140D","[unknown]",0
"WaitForSingleObjectEx",23.8634,23.8634,16.6667,16.6667,"KERNELBASE","[unknown]",0
"ReadFile",0.336139,0.336139,0.234766,0.234766,"KERNELBASE","[unknown]",0
"GetLastError",0.0421774,0.0421774,0.0294575,0.0294575,"KERNELBASE","[unknown]",0
"FlsGetValue",0.336422,0.336422,0.234964,0.234964,"KERNELBASE","[unknown]",0
"WaitForMultipleObjects",6.98464,6.98464,4.8782,4.8782,"KERNELBASE","[unknown]",0
"WaitForMultipleObjectsEx",0,0,0,0,"KERNELBASE","[unknown]",0
"ConnectNamedPipe",23.8634,23.8634,16.6667,16.6667,"KERNELBASE","[unknown]",0
"QueryPerformanceCounter",0.0125961,0.0125961,0.00879735,0.00879735,"KERNEL32","[unknown]",0
"BaseThreadInitThunk",0,71.5902,0,50,"KERNEL32","[unknown]",0
"RtlQueryPerformanceCounter",0.234327,0.234327,0.163658,0.163658,"ntdll","[unknown]",0
"RtlRestoreLastWin32Error",0.109186,0.109186,0.0762576,0.0762576,"ntdll","[unknown]",0
"RtlFlsGetValue",0,0,0,0,"ntdll","[unknown]",0
"RtlUserThreadStart",71.5903,143.181,50,100,"ntdll","[unknown]",0
"TpReleaseCleanupGroupMembers",0,0,0,0,"ntdll","[unknown]",0
"RtlRetrieveNtUserPfn",0,0,0,0,"ntdll","[unknown]",0
"NtWaitForSingleObject",0,0,0,0,"ntdll","[unknown]",0
"NtReadFile",0,0,0,0,"ntdll","[unknown]",0
"ZwFsControlFile",0,0,0,0,"ntdll","[unknown]",0
"ZwWaitForMultipleObjects",0,0,0,0,"ntdll","[unknown]",0
"ZwWaitForWorkViaWorkerFactory",0,0,0,0,"ntdll","[unknown]",0