It looks like I did not copy the list.
I did run VACUUM ANALYZE after the upgrade. I've even run it now before getting a new explain plan that is very similar (just the costs differ):Gather Merge (cost=12336145.92..16111570.23 rows=31531596 width=66)
Workers Planned: 4
-> Sort (cost=12335145.86..12354853.11 rows=7882899 width=66)
Sort Key: t.msg_status DESC, t.input_sequence
-> Parallel Hash Right Join (cost=9376528.66..11242773.26 rows=7882899 width=66)
Hash Cond: (snd_tro.reply_input_sequence = t.input_sequence)
-> Parallel Seq Scan on tbl_outputrequest snd_tro (cost=0.00..1431919.45 rows=20057645 width=16)
-> Parallel Hash (cost=9254599.76..9254599.76 rows=5245992 width=58)
-> Parallel Hash Right Join (cost=7458636.44..9254599.76 rows=5245992 width=58)
Hash Cond: (rec_tro.input_sequence = t.input_sequence)
-> Parallel Seq Scan on tbl_outputrequest rec_tro (cost=0.00..1431919.45 rows=20057645 width=16)
-> Parallel Hash (cost=7380902.99..7380902.99 rows=3491156 width=50)
-> Parallel Hash Right Join (cost=5592677.17..7380902.99 rows=3491156 width=50)
Hash Cond: (rpl_snd_tro.reply_input_sequence = r.input_sequence)
-> Parallel Seq Scan on tbl_outputrequest rpl_snd_tro (cost=0.00..1431919.45 rows=20057645 width=16)
-> Parallel Hash (cost=5518353.72..5518353.72 rows=3491156 width=42)
-> Parallel Hash Right Join (cost=3729209.40..5518353.72 rows=3491156 width=42)
Hash Cond: (rpl_rec_tro.input_sequence = r.input_sequence)
-> Parallel Seq Scan on tbl_outputrequest rpl_rec_tro (cost=0.00..1431919.45 rows=20057645 width=16)
-> Parallel Hash (cost=3658294.95..3658294.95 rows=3491156 width=34)
-> Parallel Hash Right Join (cost=1883503.35..3658294.95 rows=3491156 width=34)
Hash Cond: (r.originalrequest_id = t.input_sequence)
-> Parallel Seq Scan on tbl_inputrequest r (cost=0.00..1739752.66 rows=13348166 width=16)
-> Parallel Hash (cost=1839863.91..1839863.91 rows=3491156 width=26)
-> Parallel Seq Scan on tbl_inputrequest t (cost=0.00..1839863.91 rows=3491156 width=26)
Filter: ((receive_time < '2024-05-17 00:00:00'::timestamp without time zone) AND (input_sequence < '202406020168279904'::bigint) AND ((msg_status)::text = ANY ('{COMPLETED,REJECTED}'::text[])))The query cost is high and it returns a significant number of rows. However it should not consume 64+ GB RAM with the default enable_parallel_hash = 'on' when my shared_buffers is 8GB. The temporary fix I've implemented to get the system working is a change in the application configuration so that the timestamp filter selects fewer rows (3x less), together with setting enable_parallel_hash = 'off'. PostgreSQL service memory usage grows and stays over 20GB even with this setting. I'd like to find out exactly what causes the high memory usage as we have other projects using PostgreSQL and they are scheduled for upgrade from v12.My test.sql looks like this (application uses prepared statements, the two set operations are to revert for the current session the already implemented fixes):
prepare my_query (timestamp,bigint) as SELECT t.input_sequence, rec_tro.output_sequence, r.input_sequence, rpl_rec_tro.output_sequence, rpl_snd_tro.output_sequence, snd_tro.output_sequence, t.msg_type FROM inputrequest t LEFT JOIN outputrequest rec_tro ON rec_tro.input_sequence = t.input_sequence LEFT JOIN inputrequest r ON r.originalRequest_id = t.input_sequence LEFT JOIN outputrequest rpl_rec_tro ON rpl_rec_tro.input_sequence = r.input_sequence LEFT JOIN outputrequest rpl_snd_tro ON rpl_snd_tro.reply_input_sequence = r.input_sequence LEFT JOIN outputrequest snd_tro ON snd_tro.reply_input_sequence = t.input_sequence WHERE t.receive_time < $1 AND t.input_sequence < $2 AND t.msg_status IN ('COMPLETED', 'REJECTED') ORDER BY t.msg_status DESC, t.input_sequence ;
EXPLAIN EXECUTE my_query('2024-05-17 00:00:00', 202406020168279904);
I have an explanation for what I suspected was a memory leak. It seems that systemd reports cached memory, the relevant part is probably rss from /sys/fs/cgroup/memory/system.slice/postgresql-16.service/memory.stat . This still leaves the out of memory situation.
I've managed to get a dump of the memory contexts for the 5 processes (explain + 4 parallel workers) while the query is starting to use significant memory (>10G). I've attached it in case anyone familiar with postgresql internals can see something obviously wrong with the reported numbers.
Radu
2024-06-05 15:58:53.806 +08 [338795] LOG: logging memory contexts of PID 338795 2024-06-05 15:58:53.807 +08 [338795] LOG: level: 0; TopMemoryContext: 2269696 total in 8 blocks; 33432 free (11 chunks); 2236264 used 2024-06-05 15:58:53.807 +08 [338795] LOG: level: 1; HandleParallelMessages: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.807 +08 [338795] LOG: level: 1; TableSpace cache: 8192 total in 1 blocks; 2128 free (0 chunks); 6064 used 2024-06-05 15:58:53.807 +08 [338795] LOG: level: 1; TopTransactionContext: 8192 total in 1 blocks; 5736 free (0 chunks); 2456 used 2024-06-05 15:58:53.808 +08 [338795] LOG: level: 1; Prepared Queries: 16384 total in 2 blocks; 6696 free (3 chunks); 9688 used 2024-06-05 15:58:53.808 +08 [338795] LOG: level: 1; Type information cache: 24368 total in 2 blocks; 2648 free (0 chunks); 21720 used 2024-06-05 15:58:53.809 +08 [338795] LOG: level: 1; Operator lookup cache: 24576 total in 2 blocks; 10792 free (3 chunks); 13784 used 2024-06-05 15:58:53.809 +08 [338795] LOG: level: 1; RowDescriptionContext: 8192 total in 1 blocks; 6896 free (0 chunks); 1296 used 2024-06-05 15:58:53.809 +08 [338795] LOG: level: 1; MessageContext: 8192 total in 1 blocks; 2256 free (0 chunks); 5936 used 2024-06-05 15:58:53.809 +08 [338795] LOG: level: 1; Operator class cache: 8192 total in 1 blocks; 592 free (0 chunks); 7600 used 2024-06-05 15:58:53.810 +08 [338795] LOG: level: 1; smgr relation table: 32768 total in 3 blocks; 16880 free (8 chunks); 15888 used 2024-06-05 15:58:53.810 +08 [338795] LOG: level: 1; PgStat Shared Ref Hash: 7216 total in 2 blocks; 688 free (0 chunks); 6528 used 2024-06-05 15:58:53.810 +08 [338795] LOG: level: 1; PgStat Shared Ref: 8192 total in 4 blocks; 3704 free (4 chunks); 4488 used 2024-06-05 15:58:53.811 +08 [338795] LOG: level: 1; PgStat Pending: 16384 total in 5 blocks; 3288 free (10 chunks); 13096 used 2024-06-05 15:58:53.811 +08 [338795] LOG: level: 1; TransactionAbortContext: 32768 total in 1 blocks; 32504 free (0 chunks); 264 used 2024-06-05 15:58:53.811 +08 [338795] LOG: level: 1; Portal hash: 8192 total in 1 blocks; 592 free (0 chunks); 7600 used 2024-06-05 15:58:53.812 +08 [338795] LOG: level: 1; TopPortalContext: 8192 total in 1 blocks; 7664 free (0 chunks); 528 used 2024-06-05 15:58:53.812 +08 [338795] LOG: level: 2; PortalHoldContext: 24624 total in 2 blocks; 7400 free (0 chunks); 17224 used 2024-06-05 15:58:53.823 +08 [338795] LOG: level: 2; PortalContext: 527776 total in 83 blocks; 3760 free (6 chunks); 524016 used: <unnamed> 2024-06-05 15:58:53.824 +08 [338795] LOG: level: 3; ExecutorState: 58826880 total in 19 blocks; 3932992 free (11 chunks); 54893888 used 2024-06-05 15:58:53.824 +08 [338795] LOG: level: 4; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:58:53.825 +08 [338795] LOG: level: 5; HashSpillContext: 8192 total in 1 blocks; 4008 free (9 chunks); 4184 used 2024-06-05 15:58:53.825 +08 [338795] LOG: level: 5; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.825 +08 [338795] LOG: level: 4; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:58:53.840 +08 [338795] LOG: level: 5; HashSpillContext: 339738672 total in 47 blocks; 3740880 free (163341 chunks); 335997792 used 2024-06-05 15:58:53.840 +08 [338795] LOG: level: 5; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.841 +08 [338795] LOG: level: 4; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:58:53.841 +08 [338795] LOG: level: 5; HashSpillContext: 8192 total in 1 blocks; 4720 free (0 chunks); 3472 used 2024-06-05 15:58:53.841 +08 [338795] LOG: level: 5; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.841 +08 [338795] LOG: level: 4; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:58:53.841 +08 [338795] LOG: level: 5; HashSpillContext: 8192 total in 1 blocks; 1520 free (0 chunks); 6672 used 2024-06-05 15:58:53.841 +08 [338795] LOG: level: 5; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.841 +08 [338795] LOG: level: 4; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:58:53.842 +08 [338795] LOG: level: 5; HashSpillContext: 8192 total in 1 blocks; 1520 free (0 chunks); 6672 used 2024-06-05 15:58:53.842 +08 [338795] LOG: level: 5; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.842 +08 [338795] LOG: level: 4; TupleSort main: 32816 total in 2 blocks; 4944 free (0 chunks); 27872 used 2024-06-05 15:58:53.842 +08 [338795] LOG: level: 5; TupleSort sort: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.842 +08 [338795] LOG: level: 6; Caller tuples: 8192 total in 1 blocks (0 chunks); 7984 free (0 chunks); 208 used 2024-06-05 15:58:53.842 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.843 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.843 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.843 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.843 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.843 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.843 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.843 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.844 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.844 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.844 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.844 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.844 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.844 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.844 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.845 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.845 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.845 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.845 +08 [338795] LOG: level: 3; ExecutorState: 8192 total in 1 blocks; 4648 free (0 chunks); 3544 used 2024-06-05 15:58:53.845 +08 [338795] LOG: level: 4; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:58:53.846 +08 [338795] LOG: level: 1; Relcache by OID: 16384 total in 2 blocks; 3584 free (2 chunks); 12800 used 2024-06-05 15:58:53.847 +08 [338795] LOG: level: 1; CacheMemoryContext: 1048576 total in 8 blocks; 485792 free (0 chunks); 562784 used 2024-06-05 15:58:53.848 +08 [338795] LOG: level: 2; CachedPlan: 66560 total in 7 blocks; 8344 free (0 chunks); 58216 used: prepare my_query (timestamp,bigint) as SELECT t.input_sequence, rec_tro.output_sequence, r.input_se... 2024-06-05 15:58:53.848 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 880 free (1 chunks); 1168 used: tbl_outputrequest_reply_input_sequence_idx 2024-06-05 15:58:53.848 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 880 free (1 chunks); 1168 used: tbl_outputrequest_input_sequence_idx 2024-06-05 15:58:53.848 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 400 free (0 chunks); 1648 used: idx_status_is_ris 2024-06-05 15:58:53.848 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 0 free (0 chunks); 1024 used: tbl_outputrequest_pkey 2024-06-05 15:58:53.848 +08 [338795] LOG: level: 2; index info: 3072 total in 2 blocks; 1056 free (1 chunks); 2016 used: pg_constraint_conrelid_contypid_conname_index 2024-06-05 15:58:53.848 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 0 free (0 chunks); 1024 used: pg_statistic_ext_relid_index 2024-06-05 15:58:53.848 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 880 free (1 chunks); 1168 used: tbl_inputrequest_originalrequest_id_idx 2024-06-05 15:58:53.849 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: tbl_inputrequest_pkey 2024-06-05 15:58:53.849 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_index_indrelid_index 2024-06-05 15:58:53.849 +08 [338795] LOG: level: 2; CachedPlanSource: 16384 total in 5 blocks; 4368 free (0 chunks); 12016 used: prepare my_query (timestamp,bigint) as SELECT t.input_sequence, rec_tro.output_sequence, r.input_se... 2024-06-05 15:58:53.850 +08 [338795] LOG: level: 3; CachedPlanQuery: 131072 total in 8 blocks; 54136 free (2 chunks); 76936 used 2024-06-05 15:58:53.850 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 584 free (2 chunks); 1464 used: pg_db_role_setting_databaseid_rol_index 2024-06-05 15:58:53.850 +08 [338795] LOG: level: 2; index info: 3072 total in 2 blocks; 1088 free (2 chunks); 1984 used: pg_opclass_am_name_nsp_index 2024-06-05 15:58:53.850 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 40 free (0 chunks); 984 used: pg_foreign_data_wrapper_name_index 2024-06-05 15:58:53.850 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_enum_oid_index 2024-06-05 15:58:53.850 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 544 free (2 chunks); 1504 used: pg_class_relname_nsp_index 2024-06-05 15:58:53.850 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_foreign_server_oid_index 2024-06-05 15:58:53.850 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_publication_pubname_index 2024-06-05 15:58:53.850 +08 [338795] LOG: level: 2; index info: 3072 total in 2 blocks; 912 free (2 chunks); 2160 used: pg_statistic_relid_att_inh_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 544 free (2 chunks); 1504 used: pg_cast_source_target_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_language_name_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_transform_oid_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_collation_oid_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 3072 total in 2 blocks; 808 free (3 chunks); 2264 used: pg_amop_fam_strat_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_index_indexrelid_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 760 free (2 chunks); 1288 used: pg_ts_template_tmplname_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 3072 total in 2 blocks; 1232 free (3 chunks); 1840 used: pg_ts_config_map_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_opclass_oid_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 40 free (0 chunks); 984 used: pg_foreign_data_wrapper_oid_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 40 free (0 chunks); 984 used: pg_publication_namespace_oid_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_event_trigger_evtname_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 760 free (2 chunks); 1288 used: pg_statistic_ext_name_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_publication_oid_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_ts_dict_oid_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_event_trigger_oid_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 3072 total in 2 blocks; 1168 free (3 chunks); 1904 used: pg_conversion_default_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 3072 total in 2 blocks; 880 free (3 chunks); 2192 used: pg_operator_oprname_l_r_n_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 616 free (2 chunks); 1432 used: pg_trigger_tgrelid_tgname_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 760 free (2 chunks); 1288 used: pg_enum_typid_label_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_ts_config_oid_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_user_mapping_oid_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 3072 total in 2 blocks; 1232 free (3 chunks); 1840 used: pg_opfamily_am_name_nsp_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_foreign_table_relid_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_type_oid_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_aggregate_fnoid_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_constraint_oid_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 760 free (2 chunks); 1288 used: pg_rewrite_rel_rulename_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 760 free (2 chunks); 1288 used: pg_ts_parser_prsname_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 760 free (2 chunks); 1288 used: pg_ts_config_cfgname_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_ts_parser_oid_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 728 free (1 chunks); 1320 used: pg_publication_rel_prrelid_prpubid_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_operator_oid_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_namespace_nspname_index 2024-06-05 15:58:53.851 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_ts_template_oid_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 3072 total in 2 blocks; 944 free (2 chunks); 2128 used: pg_amop_opr_fam_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 3072 total in 2 blocks; 1200 free (3 chunks); 1872 used: pg_default_acl_role_nsp_obj_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 3072 total in 2 blocks; 1232 free (3 chunks); 1840 used: pg_collation_name_enc_nsp_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_publication_rel_oid_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_range_rngtypid_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 760 free (2 chunks); 1288 used: pg_ts_dict_dictname_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 544 free (2 chunks); 1504 used: pg_type_typname_nsp_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_opfamily_oid_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_statistic_ext_oid_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 728 free (1 chunks); 1320 used: pg_statistic_ext_data_stxoid_inh_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_class_oid_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 3072 total in 2 blocks; 1232 free (3 chunks); 1840 used: pg_proc_proname_args_nsp_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 40 free (0 chunks); 984 used: pg_partitioned_table_partrelid_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_range_rngmultitypid_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 760 free (2 chunks); 1288 used: pg_transform_type_lang_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 544 free (2 chunks); 1504 used: pg_attribute_relid_attnum_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_proc_oid_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_language_oid_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_namespace_oid_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 3072 total in 2 blocks; 808 free (3 chunks); 2264 used: pg_amproc_fam_proc_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_foreign_server_name_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 760 free (2 chunks); 1288 used: pg_attribute_relid_attnam_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 728 free (1 chunks); 1320 used: pg_publication_namespace_pnnspid_pnpubid_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_conversion_oid_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 728 free (1 chunks); 1320 used: pg_user_mapping_user_server_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 728 free (1 chunks); 1320 used: pg_subscription_rel_srrelid_srsubid_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_sequence_seqrelid_index 2024-06-05 15:58:53.852 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 760 free (2 chunks); 1288 used: pg_conversion_name_nsp_index 2024-06-05 15:58:53.853 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_authid_oid_index 2024-06-05 15:58:53.853 +08 [338795] LOG: level: 2; index info: 3072 total in 2 blocks; 1200 free (3 chunks); 1872 used: pg_auth_members_member_role_index 2024-06-05 15:58:53.853 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_subscription_oid_index 2024-06-05 15:58:53.853 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_parameter_acl_oid_index 2024-06-05 15:58:53.853 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_tablespace_oid_index 2024-06-05 15:58:53.853 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_parameter_acl_parname_index 2024-06-05 15:58:53.853 +08 [338795] LOG: level: 2; index info: 3072 total in 2 blocks; 1232 free (3 chunks); 1840 used: pg_shseclabel_object_index 2024-06-05 15:58:53.853 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 40 free (0 chunks); 984 used: pg_replication_origin_roname_index 2024-06-05 15:58:53.853 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_database_datname_index 2024-06-05 15:58:53.853 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 760 free (2 chunks); 1288 used: pg_subscription_subname_index 2024-06-05 15:58:53.853 +08 [338795] LOG: level: 2; index info: 1024 total in 1 blocks; 40 free (0 chunks); 984 used: pg_replication_origin_roiident_index 2024-06-05 15:58:53.853 +08 [338795] LOG: level: 2; index info: 3072 total in 2 blocks; 1200 free (3 chunks); 1872 used: pg_auth_members_role_member_index 2024-06-05 15:58:53.853 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_database_oid_index 2024-06-05 15:58:53.853 +08 [338795] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_authid_rolname_index 2024-06-05 15:58:53.854 +08 [338795] LOG: level: 1; WAL record construction: 50200 total in 2 blocks; 6376 free (0 chunks); 43824 used 2024-06-05 15:58:53.854 +08 [338795] LOG: level: 1; PrivateRefCount: 8192 total in 1 blocks; 2648 free (0 chunks); 5544 used 2024-06-05 15:58:53.854 +08 [338795] LOG: level: 1; MdSmgr: 8192 total in 1 blocks; 7480 free (2 chunks); 712 used 2024-06-05 15:58:53.854 +08 [338795] LOG: level: 1; LOCALLOCK hash: 8192 total in 1 blocks; 592 free (0 chunks); 7600 used 2024-06-05 15:58:53.854 +08 [338795] LOG: level: 1; GUCMemoryContext: 24576 total in 2 blocks; 9104 free (3 chunks); 15472 used 2024-06-05 15:58:53.854 +08 [338795] LOG: level: 2; GUC hash table: 32768 total in 3 blocks; 12704 free (5 chunks); 20064 used 2024-06-05 15:58:53.855 +08 [338795] LOG: level: 1; Timezones: 104112 total in 2 blocks; 2648 free (0 chunks); 101464 used 2024-06-05 15:58:53.855 +08 [338795] LOG: level: 1; ErrorContext: 8192 total in 1 blocks; 7928 free (5 chunks); 264 used 2024-06-05 15:58:53.855 +08 [338795] LOG: Grand total: 403639000 bytes in 421 blocks; 8754152 free (163510 chunks); 394884848 used 2024-06-05 15:59:00.795 +08 [338796] LOG: logging memory contexts of PID 338796 2024-06-05 15:59:00.796 +08 [338796] LOG: level: 0; TopMemoryContext: 2261504 total in 8 blocks; 35008 free (15 chunks); 2226496 used 2024-06-05 15:59:00.797 +08 [338796] LOG: level: 1; TopTransactionContext: 172112 total in 6 blocks; 65392 free (3 chunks); 106720 used 2024-06-05 15:59:00.799 +08 [338796] LOG: level: 2; ExecutorState: 94380200 total in 23 blocks; 205072 free (14 chunks); 94175128 used 2024-06-05 15:59:00.800 +08 [338796] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:59:00.800 +08 [338796] LOG: level: 4; HashSpillContext: 8192 total in 1 blocks; 4008 free (9 chunks); 4184 used 2024-06-05 15:59:00.800 +08 [338796] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.800 +08 [338796] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:59:00.815 +08 [338796] LOG: level: 4; HashSpillContext: 1559759664 total in 59375 blocks; 2169704 free (131163 chunks); 1557589960 used 2024-06-05 15:59:00.815 +08 [338796] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.815 +08 [338796] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:59:00.816 +08 [338796] LOG: level: 4; HashSpillContext: 8192 total in 1 blocks; 4720 free (0 chunks); 3472 used 2024-06-05 15:59:00.816 +08 [338796] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.816 +08 [338796] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:59:00.816 +08 [338796] LOG: level: 4; HashSpillContext: 8192 total in 1 blocks; 1520 free (0 chunks); 6672 used 2024-06-05 15:59:00.817 +08 [338796] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.817 +08 [338796] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7680 free (12 chunks); 512 used 2024-06-05 15:59:00.817 +08 [338796] LOG: level: 4; HashSpillContext: 8192 total in 1 blocks; 1520 free (0 chunks); 6672 used 2024-06-05 15:59:00.817 +08 [338796] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.818 +08 [338796] LOG: level: 3; TupleSort main: 32816 total in 2 blocks; 4800 free (12 chunks); 28016 used 2024-06-05 15:59:00.818 +08 [338796] LOG: level: 4; TupleSort sort: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.818 +08 [338796] LOG: level: 5; Caller tuples: 8192 total in 1 blocks (0 chunks); 7984 free (0 chunks); 208 used 2024-06-05 15:59:00.818 +08 [338796] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.818 +08 [338796] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.818 +08 [338796] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.818 +08 [338796] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.818 +08 [338796] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.818 +08 [338796] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.818 +08 [338796] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.819 +08 [338796] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.819 +08 [338796] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.819 +08 [338796] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.819 +08 [338796] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.819 +08 [338796] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.819 +08 [338796] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.819 +08 [338796] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.820 +08 [338796] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.820 +08 [338796] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.820 +08 [338796] LOG: level: 1; Operator class cache: 8192 total in 1 blocks; 592 free (0 chunks); 7600 used 2024-06-05 15:59:00.820 +08 [338796] LOG: level: 1; smgr relation table: 16384 total in 2 blocks; 4640 free (2 chunks); 11744 used 2024-06-05 15:59:00.820 +08 [338796] LOG: level: 1; PgStat Shared Ref Hash: 7216 total in 2 blocks; 688 free (0 chunks); 6528 used 2024-06-05 15:59:00.820 +08 [338796] LOG: level: 1; PgStat Shared Ref: 4096 total in 3 blocks; 1232 free (2 chunks); 2864 used 2024-06-05 15:59:00.820 +08 [338796] LOG: level: 1; PgStat Pending: 16384 total in 5 blocks; 6720 free (10 chunks); 9664 used 2024-06-05 15:59:00.821 +08 [338796] LOG: level: 1; TransactionAbortContext: 32768 total in 1 blocks; 32504 free (0 chunks); 264 used 2024-06-05 15:59:00.821 +08 [338796] LOG: level: 1; Portal hash: 8192 total in 1 blocks; 592 free (0 chunks); 7600 used 2024-06-05 15:59:00.821 +08 [338796] LOG: level: 1; TopPortalContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:00.821 +08 [338796] LOG: level: 1; Relcache by OID: 16384 total in 2 blocks; 3584 free (2 chunks); 12800 used 2024-06-05 15:59:00.827 +08 [338796] LOG: level: 1; CacheMemoryContext: 524288 total in 7 blocks; 253344 free (370 chunks); 270944 used 2024-06-05 15:59:00.827 +08 [338796] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_proc_oid_index 2024-06-05 15:59:00.827 +08 [338796] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_operator_oid_index 2024-06-05 15:59:00.827 +08 [338796] LOG: level: 2; index info: 3072 total in 2 blocks; 1088 free (1 chunks); 1984 used: pg_amop_opr_fam_index 2024-06-05 15:59:00.827 +08 [338796] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_type_oid_index 2024-06-05 15:59:00.827 +08 [338796] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_index_indexrelid_index 2024-06-05 15:59:00.827 +08 [338796] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_opclass_oid_index 2024-06-05 15:59:00.827 +08 [338796] LOG: level: 2; index info: 2048 total in 2 blocks; 616 free (2 chunks); 1432 used: pg_trigger_tgrelid_tgname_index 2024-06-05 15:59:00.827 +08 [338796] LOG: level: 2; index info: 2048 total in 2 blocks; 760 free (2 chunks); 1288 used: pg_rewrite_rel_rulename_index 2024-06-05 15:59:00.827 +08 [338796] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_class_oid_index 2024-06-05 15:59:00.827 +08 [338796] LOG: level: 2; index info: 2048 total in 2 blocks; 544 free (2 chunks); 1504 used: pg_attribute_relid_attnum_index 2024-06-05 15:59:00.827 +08 [338796] LOG: level: 2; index info: 3072 total in 2 blocks; 808 free (3 chunks); 2264 used: pg_amproc_fam_proc_index 2024-06-05 15:59:00.827 +08 [338796] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_authid_oid_index 2024-06-05 15:59:00.827 +08 [338796] LOG: level: 2; index info: 3072 total in 2 blocks; 1200 free (3 chunks); 1872 used: pg_auth_members_member_role_index 2024-06-05 15:59:00.827 +08 [338796] LOG: level: 2; index info: 3072 total in 2 blocks; 1232 free (3 chunks); 1840 used: pg_shseclabel_object_index 2024-06-05 15:59:00.827 +08 [338796] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_database_datname_index 2024-06-05 15:59:00.827 +08 [338796] LOG: level: 2; index info: 2048 total in 2 blocks; 984 free (1 chunks); 1064 used: pg_database_oid_index 2024-06-05 15:59:00.827 +08 [338796] LOG: level: 2; index info: 2048 total in 2 blocks; 984 free (1 chunks); 1064 used: pg_authid_rolname_index 2024-06-05 15:59:00.828 +08 [338796] LOG: level: 1; Parallel worker: 8192 total in 1 blocks; 7792 free (1 chunks); 400 used 2024-06-05 15:59:00.828 +08 [338796] LOG: level: 1; WAL record construction: 50200 total in 2 blocks; 6376 free (0 chunks); 43824 used 2024-06-05 15:59:00.828 +08 [338796] LOG: level: 1; PrivateRefCount: 8192 total in 1 blocks; 2648 free (0 chunks); 5544 used 2024-06-05 15:59:00.828 +08 [338796] LOG: level: 1; MdSmgr: 8192 total in 1 blocks; 7760 free (4 chunks); 432 used 2024-06-05 15:59:00.828 +08 [338796] LOG: level: 1; LOCALLOCK hash: 8192 total in 1 blocks; 592 free (0 chunks); 7600 used 2024-06-05 15:59:00.829 +08 [338796] LOG: level: 1; GUCMemoryContext: 24576 total in 2 blocks; 9376 free (11 chunks); 15200 used 2024-06-05 15:59:00.829 +08 [338796] LOG: level: 2; GUC hash table: 32768 total in 3 blocks; 12704 free (5 chunks); 20064 used 2024-06-05 15:59:00.829 +08 [338796] LOG: level: 1; Timezones: 104112 total in 2 blocks; 2648 free (0 chunks); 101464 used 2024-06-05 15:59:00.829 +08 [338796] LOG: level: 1; ErrorContext: 8192 total in 1 blocks; 7928 free (4 chunks); 264 used 2024-06-05 15:59:00.829 +08 [338796] LOG: Grand total: 1657801040 bytes in 59518 blocks; 3097152 free (131657 chunks); 1654703888 used 2024-06-05 15:59:05.574 +08 [338797] LOG: logging memory contexts of PID 338797 2024-06-05 15:59:05.575 +08 [338797] LOG: level: 0; TopMemoryContext: 2261504 total in 8 blocks; 35008 free (15 chunks); 2226496 used 2024-06-05 15:59:05.576 +08 [338797] LOG: level: 1; TopTransactionContext: 172112 total in 6 blocks; 65392 free (3 chunks); 106720 used 2024-06-05 15:59:05.579 +08 [338797] LOG: level: 2; ExecutorState: 102768808 total in 24 blocks; 7344400 free (13 chunks); 95424408 used 2024-06-05 15:59:05.579 +08 [338797] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:59:05.579 +08 [338797] LOG: level: 4; HashSpillContext: 8192 total in 1 blocks; 4008 free (9 chunks); 4184 used 2024-06-05 15:59:05.579 +08 [338797] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.579 +08 [338797] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:59:05.601 +08 [338797] LOG: level: 4; HashSpillContext: 2458610600 total in 102678 blocks; 9382960 free (108515 chunks); 2449227640 used 2024-06-05 15:59:05.601 +08 [338797] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.601 +08 [338797] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:59:05.602 +08 [338797] LOG: level: 4; HashSpillContext: 8192 total in 1 blocks; 4720 free (0 chunks); 3472 used 2024-06-05 15:59:05.602 +08 [338797] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.602 +08 [338797] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:59:05.603 +08 [338797] LOG: level: 4; HashSpillContext: 8192 total in 1 blocks; 1520 free (0 chunks); 6672 used 2024-06-05 15:59:05.603 +08 [338797] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.603 +08 [338797] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7680 free (12 chunks); 512 used 2024-06-05 15:59:05.603 +08 [338797] LOG: level: 4; HashSpillContext: 8192 total in 1 blocks; 1520 free (0 chunks); 6672 used 2024-06-05 15:59:05.603 +08 [338797] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.604 +08 [338797] LOG: level: 3; TupleSort main: 32816 total in 2 blocks; 4800 free (12 chunks); 28016 used 2024-06-05 15:59:05.604 +08 [338797] LOG: level: 4; TupleSort sort: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.604 +08 [338797] LOG: level: 5; Caller tuples: 8192 total in 1 blocks (0 chunks); 7984 free (0 chunks); 208 used 2024-06-05 15:59:05.604 +08 [338797] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.604 +08 [338797] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.604 +08 [338797] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.604 +08 [338797] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.604 +08 [338797] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.605 +08 [338797] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.605 +08 [338797] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.605 +08 [338797] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.605 +08 [338797] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.605 +08 [338797] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.605 +08 [338797] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.605 +08 [338797] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.605 +08 [338797] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.606 +08 [338797] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.606 +08 [338797] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.606 +08 [338797] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.606 +08 [338797] LOG: level: 1; Operator class cache: 8192 total in 1 blocks; 592 free (0 chunks); 7600 used 2024-06-05 15:59:05.606 +08 [338797] LOG: level: 1; smgr relation table: 16384 total in 2 blocks; 4640 free (2 chunks); 11744 used 2024-06-05 15:59:05.606 +08 [338797] LOG: level: 1; PgStat Shared Ref Hash: 7216 total in 2 blocks; 688 free (0 chunks); 6528 used 2024-06-05 15:59:05.606 +08 [338797] LOG: level: 1; PgStat Shared Ref: 4096 total in 3 blocks; 1232 free (2 chunks); 2864 used 2024-06-05 15:59:05.606 +08 [338797] LOG: level: 1; PgStat Pending: 16384 total in 5 blocks; 6720 free (10 chunks); 9664 used 2024-06-05 15:59:05.607 +08 [338797] LOG: level: 1; TransactionAbortContext: 32768 total in 1 blocks; 32504 free (0 chunks); 264 used 2024-06-05 15:59:05.607 +08 [338797] LOG: level: 1; Portal hash: 8192 total in 1 blocks; 592 free (0 chunks); 7600 used 2024-06-05 15:59:05.607 +08 [338797] LOG: level: 1; TopPortalContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:05.607 +08 [338797] LOG: level: 1; Relcache by OID: 16384 total in 2 blocks; 3584 free (2 chunks); 12800 used 2024-06-05 15:59:05.612 +08 [338797] LOG: level: 1; CacheMemoryContext: 524288 total in 7 blocks; 253344 free (370 chunks); 270944 used 2024-06-05 15:59:05.612 +08 [338797] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_proc_oid_index 2024-06-05 15:59:05.612 +08 [338797] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_operator_oid_index 2024-06-05 15:59:05.612 +08 [338797] LOG: level: 2; index info: 3072 total in 2 blocks; 1088 free (1 chunks); 1984 used: pg_amop_opr_fam_index 2024-06-05 15:59:05.612 +08 [338797] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_type_oid_index 2024-06-05 15:59:05.612 +08 [338797] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_index_indexrelid_index 2024-06-05 15:59:05.612 +08 [338797] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_opclass_oid_index 2024-06-05 15:59:05.612 +08 [338797] LOG: level: 2; index info: 2048 total in 2 blocks; 616 free (2 chunks); 1432 used: pg_trigger_tgrelid_tgname_index 2024-06-05 15:59:05.612 +08 [338797] LOG: level: 2; index info: 2048 total in 2 blocks; 760 free (2 chunks); 1288 used: pg_rewrite_rel_rulename_index 2024-06-05 15:59:05.612 +08 [338797] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_class_oid_index 2024-06-05 15:59:05.612 +08 [338797] LOG: level: 2; index info: 2048 total in 2 blocks; 544 free (2 chunks); 1504 used: pg_attribute_relid_attnum_index 2024-06-05 15:59:05.612 +08 [338797] LOG: level: 2; index info: 3072 total in 2 blocks; 808 free (3 chunks); 2264 used: pg_amproc_fam_proc_index 2024-06-05 15:59:05.612 +08 [338797] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_authid_oid_index 2024-06-05 15:59:05.613 +08 [338797] LOG: level: 2; index info: 3072 total in 2 blocks; 1200 free (3 chunks); 1872 used: pg_auth_members_member_role_index 2024-06-05 15:59:05.613 +08 [338797] LOG: level: 2; index info: 3072 total in 2 blocks; 1232 free (3 chunks); 1840 used: pg_shseclabel_object_index 2024-06-05 15:59:05.613 +08 [338797] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_database_datname_index 2024-06-05 15:59:05.613 +08 [338797] LOG: level: 2; index info: 2048 total in 2 blocks; 984 free (1 chunks); 1064 used: pg_database_oid_index 2024-06-05 15:59:05.613 +08 [338797] LOG: level: 2; index info: 2048 total in 2 blocks; 984 free (1 chunks); 1064 used: pg_authid_rolname_index 2024-06-05 15:59:05.613 +08 [338797] LOG: level: 1; Parallel worker: 8192 total in 1 blocks; 7792 free (1 chunks); 400 used 2024-06-05 15:59:05.613 +08 [338797] LOG: level: 1; WAL record construction: 50200 total in 2 blocks; 6376 free (0 chunks); 43824 used 2024-06-05 15:59:05.613 +08 [338797] LOG: level: 1; PrivateRefCount: 8192 total in 1 blocks; 2648 free (0 chunks); 5544 used 2024-06-05 15:59:05.613 +08 [338797] LOG: level: 1; MdSmgr: 8192 total in 1 blocks; 7760 free (4 chunks); 432 used 2024-06-05 15:59:05.614 +08 [338797] LOG: level: 1; LOCALLOCK hash: 8192 total in 1 blocks; 592 free (0 chunks); 7600 used 2024-06-05 15:59:05.614 +08 [338797] LOG: level: 1; GUCMemoryContext: 24576 total in 2 blocks; 9376 free (11 chunks); 15200 used 2024-06-05 15:59:05.614 +08 [338797] LOG: level: 2; GUC hash table: 32768 total in 3 blocks; 12704 free (5 chunks); 20064 used 2024-06-05 15:59:05.614 +08 [338797] LOG: level: 1; Timezones: 104112 total in 2 blocks; 2648 free (0 chunks); 101464 used 2024-06-05 15:59:05.614 +08 [338797] LOG: level: 1; ErrorContext: 8192 total in 1 blocks; 7928 free (4 chunks); 264 used 2024-06-05 15:59:05.614 +08 [338797] LOG: Grand total: 2565040584 bytes in 102822 blocks; 17449736 free (109008 chunks); 2547590848 used 2024-06-05 15:59:10.467 +08 [338798] LOG: logging memory contexts of PID 338798 2024-06-05 15:59:10.584 +08 [338798] LOG: level: 0; TopMemoryContext: 2261504 total in 8 blocks; 35008 free (15 chunks); 2226496 used 2024-06-05 15:59:10.590 +08 [338798] LOG: level: 1; TopTransactionContext: 172112 total in 6 blocks; 65392 free (3 chunks); 106720 used 2024-06-05 15:59:10.601 +08 [338798] LOG: level: 2; ExecutorState: 102768808 total in 24 blocks; 6062856 free (16 chunks); 96705952 used 2024-06-05 15:59:10.601 +08 [338798] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:59:10.601 +08 [338798] LOG: level: 4; HashSpillContext: 8192 total in 1 blocks; 4008 free (9 chunks); 4184 used 2024-06-05 15:59:10.602 +08 [338798] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.602 +08 [338798] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:59:10.726 +08 [338798] LOG: level: 4; HashSpillContext: 3332169320 total in 145158 blocks; 8206752 free (85662 chunks); 3323962568 used 2024-06-05 15:59:10.726 +08 [338798] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.727 +08 [338798] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:59:10.727 +08 [338798] LOG: level: 4; HashSpillContext: 8192 total in 1 blocks; 4720 free (0 chunks); 3472 used 2024-06-05 15:59:10.727 +08 [338798] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.728 +08 [338798] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:59:10.728 +08 [338798] LOG: level: 4; HashSpillContext: 8192 total in 1 blocks; 1520 free (0 chunks); 6672 used 2024-06-05 15:59:10.728 +08 [338798] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.728 +08 [338798] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7680 free (12 chunks); 512 used 2024-06-05 15:59:10.729 +08 [338798] LOG: level: 4; HashSpillContext: 8192 total in 1 blocks; 1520 free (0 chunks); 6672 used 2024-06-05 15:59:10.729 +08 [338798] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.730 +08 [338798] LOG: level: 3; TupleSort main: 32816 total in 2 blocks; 4800 free (12 chunks); 28016 used 2024-06-05 15:59:10.730 +08 [338798] LOG: level: 4; TupleSort sort: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.730 +08 [338798] LOG: level: 5; Caller tuples: 8192 total in 1 blocks (0 chunks); 7984 free (0 chunks); 208 used 2024-06-05 15:59:10.730 +08 [338798] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.730 +08 [338798] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.730 +08 [338798] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.730 +08 [338798] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.730 +08 [338798] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.730 +08 [338798] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.730 +08 [338798] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.730 +08 [338798] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.731 +08 [338798] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.731 +08 [338798] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.731 +08 [338798] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.731 +08 [338798] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.731 +08 [338798] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.732 +08 [338798] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.732 +08 [338798] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.732 +08 [338798] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.732 +08 [338798] LOG: level: 1; Operator class cache: 8192 total in 1 blocks; 592 free (0 chunks); 7600 used 2024-06-05 15:59:10.732 +08 [338798] LOG: level: 1; smgr relation table: 16384 total in 2 blocks; 4640 free (2 chunks); 11744 used 2024-06-05 15:59:10.732 +08 [338798] LOG: level: 1; PgStat Shared Ref Hash: 7216 total in 2 blocks; 688 free (0 chunks); 6528 used 2024-06-05 15:59:10.732 +08 [338798] LOG: level: 1; PgStat Shared Ref: 4096 total in 3 blocks; 1232 free (2 chunks); 2864 used 2024-06-05 15:59:10.732 +08 [338798] LOG: level: 1; PgStat Pending: 16384 total in 5 blocks; 6720 free (10 chunks); 9664 used 2024-06-05 15:59:10.733 +08 [338798] LOG: level: 1; TransactionAbortContext: 32768 total in 1 blocks; 32504 free (0 chunks); 264 used 2024-06-05 15:59:10.733 +08 [338798] LOG: level: 1; Portal hash: 8192 total in 1 blocks; 592 free (0 chunks); 7600 used 2024-06-05 15:59:10.733 +08 [338798] LOG: level: 1; TopPortalContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:10.734 +08 [338798] LOG: level: 1; Relcache by OID: 16384 total in 2 blocks; 3584 free (2 chunks); 12800 used 2024-06-05 15:59:10.738 +08 [338798] LOG: level: 1; CacheMemoryContext: 524288 total in 7 blocks; 253344 free (370 chunks); 270944 used 2024-06-05 15:59:10.739 +08 [338798] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_proc_oid_index 2024-06-05 15:59:10.739 +08 [338798] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_operator_oid_index 2024-06-05 15:59:10.739 +08 [338798] LOG: level: 2; index info: 3072 total in 2 blocks; 1088 free (1 chunks); 1984 used: pg_amop_opr_fam_index 2024-06-05 15:59:10.739 +08 [338798] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_type_oid_index 2024-06-05 15:59:10.739 +08 [338798] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_index_indexrelid_index 2024-06-05 15:59:10.739 +08 [338798] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_opclass_oid_index 2024-06-05 15:59:10.739 +08 [338798] LOG: level: 2; index info: 2048 total in 2 blocks; 616 free (2 chunks); 1432 used: pg_trigger_tgrelid_tgname_index 2024-06-05 15:59:10.739 +08 [338798] LOG: level: 2; index info: 2048 total in 2 blocks; 760 free (2 chunks); 1288 used: pg_rewrite_rel_rulename_index 2024-06-05 15:59:10.739 +08 [338798] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_class_oid_index 2024-06-05 15:59:10.739 +08 [338798] LOG: level: 2; index info: 2048 total in 2 blocks; 544 free (2 chunks); 1504 used: pg_attribute_relid_attnum_index 2024-06-05 15:59:10.739 +08 [338798] LOG: level: 2; index info: 3072 total in 2 blocks; 808 free (3 chunks); 2264 used: pg_amproc_fam_proc_index 2024-06-05 15:59:10.739 +08 [338798] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_authid_oid_index 2024-06-05 15:59:10.739 +08 [338798] LOG: level: 2; index info: 3072 total in 2 blocks; 1200 free (3 chunks); 1872 used: pg_auth_members_member_role_index 2024-06-05 15:59:10.739 +08 [338798] LOG: level: 2; index info: 3072 total in 2 blocks; 1232 free (3 chunks); 1840 used: pg_shseclabel_object_index 2024-06-05 15:59:10.739 +08 [338798] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_database_datname_index 2024-06-05 15:59:10.739 +08 [338798] LOG: level: 2; index info: 2048 total in 2 blocks; 984 free (1 chunks); 1064 used: pg_database_oid_index 2024-06-05 15:59:10.739 +08 [338798] LOG: level: 2; index info: 2048 total in 2 blocks; 984 free (1 chunks); 1064 used: pg_authid_rolname_index 2024-06-05 15:59:10.739 +08 [338798] LOG: level: 1; Parallel worker: 8192 total in 1 blocks; 7792 free (1 chunks); 400 used 2024-06-05 15:59:10.740 +08 [338798] LOG: level: 1; WAL record construction: 50200 total in 2 blocks; 6376 free (0 chunks); 43824 used 2024-06-05 15:59:10.740 +08 [338798] LOG: level: 1; PrivateRefCount: 8192 total in 1 blocks; 2648 free (0 chunks); 5544 used 2024-06-05 15:59:10.740 +08 [338798] LOG: level: 1; MdSmgr: 8192 total in 1 blocks; 7760 free (4 chunks); 432 used 2024-06-05 15:59:10.740 +08 [338798] LOG: level: 1; LOCALLOCK hash: 8192 total in 1 blocks; 592 free (0 chunks); 7600 used 2024-06-05 15:59:10.740 +08 [338798] LOG: level: 1; GUCMemoryContext: 24576 total in 2 blocks; 9376 free (11 chunks); 15200 used 2024-06-05 15:59:10.740 +08 [338798] LOG: level: 2; GUC hash table: 32768 total in 3 blocks; 12704 free (5 chunks); 20064 used 2024-06-05 15:59:10.741 +08 [338798] LOG: level: 1; Timezones: 104112 total in 2 blocks; 2648 free (0 chunks); 101464 used 2024-06-05 15:59:10.741 +08 [338798] LOG: level: 1; ErrorContext: 8192 total in 1 blocks; 7928 free (4 chunks); 264 used 2024-06-05 15:59:10.741 +08 [338798] LOG: Grand total: 3438599304 bytes in 145302 blocks; 14991984 free (86158 chunks); 3423607320 used 2024-06-05 15:59:16.612 +08 [338799] LOG: logging memory contexts of PID 338799 2024-06-05 15:59:16.613 +08 [338799] LOG: level: 0; TopMemoryContext: 2261504 total in 8 blocks; 35008 free (15 chunks); 2226496 used 2024-06-05 15:59:16.614 +08 [338799] LOG: level: 1; TopTransactionContext: 172112 total in 6 blocks; 65392 free (3 chunks); 106720 used 2024-06-05 15:59:16.617 +08 [338799] LOG: level: 2; ExecutorState: 102768808 total in 24 blocks; 4473432 free (17 chunks); 98295376 used 2024-06-05 15:59:16.617 +08 [338799] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:59:16.617 +08 [338799] LOG: level: 4; HashSpillContext: 16504 total in 2 blocks; 3848 free (8 chunks); 12656 used 2024-06-05 15:59:16.617 +08 [338799] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.617 +08 [338799] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:59:16.650 +08 [338799] LOG: level: 4; HashSpillContext: 4329646704 total in 193664 blocks; 6750424 free (64461 chunks); 4322896280 used 2024-06-05 15:59:16.651 +08 [338799] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.651 +08 [338799] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:59:16.651 +08 [338799] LOG: level: 4; HashSpillContext: 8192 total in 1 blocks; 4720 free (0 chunks); 3472 used 2024-06-05 15:59:16.651 +08 [338799] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.652 +08 [338799] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7752 free (0 chunks); 440 used 2024-06-05 15:59:16.652 +08 [338799] LOG: level: 4; HashSpillContext: 8192 total in 1 blocks; 1520 free (0 chunks); 6672 used 2024-06-05 15:59:16.652 +08 [338799] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.652 +08 [338799] LOG: level: 3; HashTableContext: 8192 total in 1 blocks; 7680 free (12 chunks); 512 used 2024-06-05 15:59:16.652 +08 [338799] LOG: level: 4; HashSpillContext: 8192 total in 1 blocks; 1520 free (0 chunks); 6672 used 2024-06-05 15:59:16.652 +08 [338799] LOG: level: 4; HashBatchContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.653 +08 [338799] LOG: level: 3; TupleSort main: 32816 total in 2 blocks; 4800 free (12 chunks); 28016 used 2024-06-05 15:59:16.653 +08 [338799] LOG: level: 4; TupleSort sort: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.653 +08 [338799] LOG: level: 5; Caller tuples: 8192 total in 1 blocks (0 chunks); 7984 free (0 chunks); 208 used 2024-06-05 15:59:16.653 +08 [338799] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.653 +08 [338799] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.653 +08 [338799] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.653 +08 [338799] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.653 +08 [338799] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.653 +08 [338799] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.653 +08 [338799] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.654 +08 [338799] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.654 +08 [338799] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.654 +08 [338799] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.654 +08 [338799] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.654 +08 [338799] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.654 +08 [338799] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.654 +08 [338799] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.655 +08 [338799] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.655 +08 [338799] LOG: level: 3; ExprContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.655 +08 [338799] LOG: level: 1; Operator class cache: 8192 total in 1 blocks; 592 free (0 chunks); 7600 used 2024-06-05 15:59:16.655 +08 [338799] LOG: level: 1; smgr relation table: 16384 total in 2 blocks; 4640 free (2 chunks); 11744 used 2024-06-05 15:59:16.655 +08 [338799] LOG: level: 1; PgStat Shared Ref Hash: 7216 total in 2 blocks; 688 free (0 chunks); 6528 used 2024-06-05 15:59:16.655 +08 [338799] LOG: level: 1; PgStat Shared Ref: 4096 total in 3 blocks; 1232 free (2 chunks); 2864 used 2024-06-05 15:59:16.655 +08 [338799] LOG: level: 1; PgStat Pending: 16384 total in 5 blocks; 6720 free (10 chunks); 9664 used 2024-06-05 15:59:16.655 +08 [338799] LOG: level: 1; TransactionAbortContext: 32768 total in 1 blocks; 32504 free (0 chunks); 264 used 2024-06-05 15:59:16.655 +08 [338799] LOG: level: 1; Portal hash: 8192 total in 1 blocks; 592 free (0 chunks); 7600 used 2024-06-05 15:59:16.655 +08 [338799] LOG: level: 1; TopPortalContext: 8192 total in 1 blocks; 7928 free (0 chunks); 264 used 2024-06-05 15:59:16.656 +08 [338799] LOG: level: 1; Relcache by OID: 16384 total in 2 blocks; 3584 free (2 chunks); 12800 used 2024-06-05 15:59:16.660 +08 [338799] LOG: level: 1; CacheMemoryContext: 524288 total in 7 blocks; 253344 free (370 chunks); 270944 used 2024-06-05 15:59:16.660 +08 [338799] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_proc_oid_index 2024-06-05 15:59:16.660 +08 [338799] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_operator_oid_index 2024-06-05 15:59:16.660 +08 [338799] LOG: level: 2; index info: 3072 total in 2 blocks; 1088 free (1 chunks); 1984 used: pg_amop_opr_fam_index 2024-06-05 15:59:16.660 +08 [338799] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_type_oid_index 2024-06-05 15:59:16.660 +08 [338799] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_index_indexrelid_index 2024-06-05 15:59:16.660 +08 [338799] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_opclass_oid_index 2024-06-05 15:59:16.660 +08 [338799] LOG: level: 2; index info: 2048 total in 2 blocks; 616 free (2 chunks); 1432 used: pg_trigger_tgrelid_tgname_index 2024-06-05 15:59:16.660 +08 [338799] LOG: level: 2; index info: 2048 total in 2 blocks; 760 free (2 chunks); 1288 used: pg_rewrite_rel_rulename_index 2024-06-05 15:59:16.660 +08 [338799] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_class_oid_index 2024-06-05 15:59:16.660 +08 [338799] LOG: level: 2; index info: 2048 total in 2 blocks; 544 free (2 chunks); 1504 used: pg_attribute_relid_attnum_index 2024-06-05 15:59:16.660 +08 [338799] LOG: level: 2; index info: 3072 total in 2 blocks; 808 free (3 chunks); 2264 used: pg_amproc_fam_proc_index 2024-06-05 15:59:16.661 +08 [338799] LOG: level: 2; index info: 2048 total in 2 blocks; 912 free (0 chunks); 1136 used: pg_authid_oid_index 2024-06-05 15:59:16.661 +08 [338799] LOG: level: 2; index info: 3072 total in 2 blocks; 1200 free (3 chunks); 1872 used: pg_auth_members_member_role_index 2024-06-05 15:59:16.661 +08 [338799] LOG: level: 2; index info: 3072 total in 2 blocks; 1232 free (3 chunks); 1840 used: pg_shseclabel_object_index 2024-06-05 15:59:16.661 +08 [338799] LOG: level: 2; index info: 1024 total in 1 blocks; 72 free (0 chunks); 952 used: pg_database_datname_index 2024-06-05 15:59:16.661 +08 [338799] LOG: level: 2; index info: 2048 total in 2 blocks; 984 free (1 chunks); 1064 used: pg_database_oid_index 2024-06-05 15:59:16.661 +08 [338799] LOG: level: 2; index info: 2048 total in 2 blocks; 984 free (1 chunks); 1064 used: pg_authid_rolname_index 2024-06-05 15:59:16.661 +08 [338799] LOG: level: 1; Parallel worker: 8192 total in 1 blocks; 7792 free (1 chunks); 400 used 2024-06-05 15:59:16.661 +08 [338799] LOG: level: 1; WAL record construction: 50200 total in 2 blocks; 6376 free (0 chunks); 43824 used 2024-06-05 15:59:16.661 +08 [338799] LOG: level: 1; PrivateRefCount: 8192 total in 1 blocks; 2648 free (0 chunks); 5544 used 2024-06-05 15:59:16.661 +08 [338799] LOG: level: 1; MdSmgr: 8192 total in 1 blocks; 7760 free (4 chunks); 432 used 2024-06-05 15:59:16.661 +08 [338799] LOG: level: 1; LOCALLOCK hash: 8192 total in 1 blocks; 592 free (0 chunks); 7600 used 2024-06-05 15:59:16.662 +08 [338799] LOG: level: 1; GUCMemoryContext: 24576 total in 2 blocks; 9376 free (11 chunks); 15200 used 2024-06-05 15:59:16.662 +08 [338799] LOG: level: 2; GUC hash table: 32768 total in 3 blocks; 12704 free (5 chunks); 20064 used 2024-06-05 15:59:16.662 +08 [338799] LOG: level: 1; Timezones: 104112 total in 2 blocks; 2648 free (0 chunks); 101464 used 2024-06-05 15:59:16.662 +08 [338799] LOG: level: 1; ErrorContext: 8192 total in 1 blocks; 7928 free (4 chunks); 264 used 2024-06-05 15:59:16.662 +08 [338799] LOG: Grand total: 4436085000 bytes in 193809 blocks; 11946072 free (64957 chunks); 4424138928 used 2024-06-05 15:59:34.165 +08 [329010] LOG: checkpoint complete: wrote 12698 buffers (1.2%); 0 WAL file(s) added, 0 removed, 6 recycled; write=269.973 s, sync=0.101 s, total=270.078 s; sync files=38, longest=0.091 s, average=0.003 s; distance=101493 kB, estimate=188549 kB; lsn=166/4EAC87D8, redo lsn=166/489F0B60 2024-06-05 15:59:42.257 +08 [338795] ERROR: canceling statement due to user request
