Server crash occurs when try to run PG server(forked version) regression with latest PG_TDE source using access method tde_heap_basic.

Description

Server crash occur when try to run Forked PG server regression test with ‘pg_tde_basic’. Its working fine with ‘tde_heap’

This issue was reproducing after
95018185ad5b26a0e93ccf044689cc7b685182da

Environment

None

Activity

Show:

shahidullah.khan November 13, 2024 at 5:48 PM

this issue is not reproducing with latest commits on server and pg_tde repos.
verified against:
Server commit id: 197725950c5a063f737962cabb5518c8ac3d04b2
pg_tde commit id: a81a4f047914a27f79d7f99d04486cc697fd0d26

shahidullah.khan November 12, 2024 at 2:35 PM
Edited

core dumps zip file

shahidullah.khan November 6, 2024 at 6:41 PM

here is server log information.
2024-11-06 18:32:57.054 UTC [9680] STATEMENT: CREATE TABLE CHAR_TBL(f1 char(4));
2024-11-06 18:32:57.054 UTC [9680] DEBUG: 00000: EventTriggerInvoke 24607
2024-11-06 18:32:57.054 UTC [9680] LOCATION: EventTriggerInvoke, event_trigger.c:1096
2024-11-06 18:32:57.054 UTC [9680] STATEMENT: CREATE TABLE CHAR_TBL(f1 char(4));
2024-11-06 18:32:57.054 UTC [9680] LOG: 00000: EVENT TRIGGER (ddl_command_start) {CREATESTMT :relation {RANGEVAR :catalogname <> :schemaname <> :relname char_tbl :inh true :relpersistence p :alias <> :location -1} :tableElts ({COLUMNDEF :colname f1 :typeName {TYPENAME :names ("pg_catalog" "bpchar") :typeOid 0 :setof false :pct_type false :typmods ({A_CONST :val 4 :location -1}) :typemod -1 :arrayBounds <> :location -1} :compression <> :inhcount 0 :is_local true :is_not_null false :is_from_type false :storage <> :storage_name <> :raw_default <> :cooked_default <> :identity <> :identitySequence <> :generated <> :collClause <> :collOid 0 :constraints <> :fdwoptions <> :location -1}) :inhRelations <> :partbound <> :partspec <> :ofTypename <> :constraints <> :options <> :oncommit 0 :tablespacename <> :accessMethod <> :if_not_exists false}
2024-11-06 18:32:57.054 UTC [9680] LOCATION: pg_tde_ddl_command_start_capture, pg_tde_event_capture.c:72
2024-11-06 18:32:57.054 UTC [9680] STATEMENT: CREATE TABLE CHAR_TBL(f1 char(4));
2024-11-06 18:32:57.054 UTC [9680] DEBUG: 00000: creating key file for relation char_tbl
2024-11-06 18:32:57.054 UTC [9680] LOCATION: pg_tdeam_relation_set_new_filelocator, pg_tdeam_handler.c:645
2024-11-06 18:32:57.054 UTC [9680] STATEMENT: CREATE TABLE CHAR_TBL(f1 char(4));
2024-11-06 18:32:57.062 UTC [9680] DEBUG: 00000: EventTriggerInvoke 24608
2024-11-06 18:32:57.062 UTC [9680] LOCATION: EventTriggerInvoke, event_trigger.c:1096
2024-11-06 18:32:57.062 UTC [9680] STATEMENT: CREATE TABLE CHAR_TBL(f1 char(4));
2024-11-06 18:32:57.062 UTC [9680] LOG: 00000: Type:CREATE TABLE EncryptMode:false, Oid:0, Relation:char_tbl
2024-11-06 18:32:57.062 UTC [9680] LOCATION: pg_tde_ddl_command_end_capture, pg_tde_event_capture.c:192
2024-11-06 18:32:57.062 UTC [9680] STATEMENT: CREATE TABLE CHAR_TBL(f1 char(4));
2024-11-06 18:32:57.062 UTC [9680] DEBUG: 00000: CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 781/1/1
2024-11-06 18:32:57.062 UTC [9680] LOCATION: ShowTransactionStateRec, xact.c:5626
2024-11-06 18:32:57.062 UTC [9680] STATEMENT: CREATE TABLE CHAR_TBL(f1 char(4));
2024-11-06 18:32:57.065 UTC [9680] LOG: 00000: duration: 11.396 ms statement: CREATE TABLE CHAR_TBL(f1 char(4));
2024-11-06 18:32:57.065 UTC [9680] LOCATION: exec_simple_query, postgres.c:1373
2024-11-06 18:32:57.281 UTC [9673] DEBUG: 00000: snapshot of 0+0 running transaction ids (lsn 0/1DF0968 oldest xid 782 latest complete 781 next xid 782)
2024-11-06 18:32:57.281 UTC [9673] LOCATION: LogCurrentRunningXacts, standby.c:1378
2024-11-06 18:33:05.196 UTC [9680] DEBUG: 00000: StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-11-06 18:33:05.196 UTC [9680] LOCATION: ShowTransactionStateRec, xact.c:5626
2024-11-06 18:33:05.196 UTC [9680] STATEMENT: INSERT INTO CHAR_TBL (f1) VALUES
('a'),
('ab'),
('abcd'),
('abcd ');
2024-11-06 18:33:05.343 UTC [9669] DEBUG: 00000: reaping dead processes
2024-11-06 18:33:05.343 UTC [9669] LOCATION: process_pm_child_exit, postmaster.c:2365
2024-11-06 18:33:05.343 UTC [9669] DEBUG: 00000: server process (PID 9680) was terminated by signal 11: Segmentation fault
2024-11-06 18:33:05.343 UTC [9669] DETAIL: Failed process was running: INSERT INTO CHAR_TBL (f1) VALUES
('a'),
('ab'),
('abcd'),
('abcd ');
2024-11-06 18:33:05.343 UTC [9669] LOCATION: LogChildExit, postmaster.c:3102
2024-11-06 18:33:05.343 UTC [9669] LOG: 00000: server process (PID 9680) was terminated by signal 11: Segmentation fault
2024-11-06 18:33:05.343 UTC [9669] DETAIL: Failed process was running: INSERT INTO CHAR_TBL (f1) VALUES
('a'),
('ab'),
('abcd'),
('abcd ');
2024-11-06 18:33:05.343 UTC [9669] LOCATION: LogChildExit, postmaster.c:3102
2024-11-06 18:33:05.343 UTC [9669] LOG: 00000: terminating any other active server processes
2024-11-06 18:33:05.343 UTC [9669] LOCATION: HandleChildCrash, postmaster.c:2898
2024-11-06 18:33:05.343 UTC [9669] DEBUG: 00000: sending SIGQUIT to process 9677
2024-11-06 18:33:05.343 UTC [9669] LOCATION: sigquit_child, postmaster.c:3458
2024-11-06 18:33:05.343 UTC [9669] DEBUG: 00000: sending SIGQUIT to process 9673
2024-11-06 18:33:05.343 UTC [9669] LOCATION: sigquit_child, postmaster.c:3458

shahidullah.khan November 5, 2024 at 1:49 PM

Here is the statements where crash occur.

Cannot Reproduce

Details

Assignee

Reporter

Regression Issue

Yes

Components

Sprint

Priority

Smart Checklist

Created November 5, 2024 at 9:59 AM
Updated November 14, 2024 at 9:11 PM
Resolved November 14, 2024 at 9:11 PM