Hello Team,
I am trying to use the PLV8 via function and while using the function created via PLV8 in one of the create materialized view, postgres crashes, attached is the log file with DEBUG5 turned on.
SQL which is breaking the code and SQL function is attached.
Creating materialized view - mat_view_by_product - is the one which causes the crash.
I have tested the same in below environments
1. Test 1 - Passed
Mac , Postgres - 10.4, PLV8 - 2.1.0
2. Test 2 - Passed
AWS RDS , Postgres - 9.6.6 , PLV8 - 1.5.0
3. Test 3 - Fail, This test was passing earlier on 10.3 but fails on 10.4
AWS EC2 , Postgres - 10.4, PLV8 - 2.3.4
4. Test 4 - Fail
AWS RDS , Postgres - 10.3 , PLV8 - 2.1.0
Please let me know if any more information is required to assist in this problem.
Thanks for your help.
2018-06-20 19:06:18 UTC [1955]: [235-1] user=,db=,app=,client= DEBUG: forked new backend, pid=2861 socket=11 2018-06-20 19:06:18 UTC [2861]: [1-1] user=[unknown],db=[unknown],app=[unknown],client=localhost LOG: connection received: host=localhost port=37222 2018-06-20 19:06:18 UTC [2861]: [2-1] user=[unknown],db=[unknown],app=[unknown],client=localhost DEBUG: SSL connection from "(anonymous)" 2018-06-20 19:06:18 UTC [2861]: [3-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: postgres child[2861]: starting with ( 2018-06-20 19:06:18 UTC [2861]: [4-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: postgres 2018-06-20 19:06:18 UTC [2861]: [5-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: ) 2018-06-20 19:06:18 UTC [2861]: [6-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: InitPostgres 2018-06-20 19:06:18 UTC [2861]: [7-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: my backend ID is 3 2018-06-20 19:06:18 UTC [2861]: [8-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0 2018-06-20 19:06:18 UTC [2861]: [9-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: received password packet 2018-06-20 19:06:18 UTC [2861]: [10-1] user=postgres,db=procured,app=[unknown],client=localhost LOG: connection authorized: user=postgres database=procured SSL enabled (protocol=TLSv1.2, cipher=ECDHE-RSA-AES256-GCM-SHA384, compression=off) 2018-06-20 19:06:18 UTC [2861]: [11-1] user=postgres,db=procured,app=psql,client=localhost DEBUG: CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGR, xid/subid/cid: 0/1/0 2018-06-20 19:06:18 UTC [2830]: [2-1] user=,db=,app=,client= DEBUG: snapshot of 0+0 running transaction ids (lsn 0/72FC0F20 oldest xid 782 latest complete 781 next xid 782) 2018-06-20 19:06:27 UTC [2861]: [12-1] user=postgres,db=procured,app=psql,client=localhost DEBUG: StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0 2018-06-20 19:06:27 UTC [2861]: [13-1] user=postgres,db=procured,app=psql,client=localhost DEBUG: building index "pg_toast_49152_index" on table "pg_toast_49152" 2018-06-20 19:06:27 UTC [2861]: [14-1] user=postgres,db=procured,app=psql,client=localhost DEBUG: no icu dir 2018-06-20 19:06:27 UTC [2861]: [15-1] user=postgres,db=procured,app=psql,client=localhost CONTEXT: PL/pgSQL function histogram_merge(histogram,histogram) line 3 at RETURN 2018-06-20 19:06:27 UTC [1955]: [236-1] user=,db=,app=,client= DEBUG: reaping dead processes 2018-06-20 19:06:27 UTC [1955]: [237-1] user=,db=,app=,client= DEBUG: server process (PID 2861) was terminated by signal 11: Segmentation fault 2018-06-20 19:06:27 UTC [1955]: [238-1] user=,db=,app=,client= DETAIL: Failed process was running: CREATE MATERIALIZED VIEW mat_view_by_product AS SELECT facility_alias_id, group_type_id, product_id, po_date_month,histogram_agg(histogram) AS histogram, sum(total_spend) AS total_spend, min(min_price) AS min_price, max(max_price) AS max_price, min(min_po_date) AS min_po_date, max(max_po_date) AS max_po_date, (array_agg(most_recent_price ORDER BY max_po_date))[1] AS most_recent_price, sum(total_eaches) AS total_eaches FROM mat_view_by_catalog GROUP BY facility_alias_id, group_type_id, product_id, po_date_month; 2018-06-20 19:06:27 UTC [1955]: [239-1] user=,db=,app=,client= LOG: server process (PID 2861) was terminated by signal 11: Segmentation fault 2018-06-20 19:06:27 UTC [1955]: [240-1] user=,db=,app=,client= DETAIL: Failed process was running: CREATE MATERIALIZED VIEW mat_view_by_product AS SELECT facility_alias_id, group_type_id, product_id, po_date_month,histogram_agg(histogram) AS histogram, sum(total_spend) AS total_spend, min(min_price) AS min_price, max(max_price) AS max_price, min(min_po_date) AS min_po_date, max(max_po_date) AS max_po_date, (array_agg(most_recent_price ORDER BY max_po_date))[1] AS most_recent_price, sum(total_eaches) AS total_eaches FROM mat_view_by_catalog GROUP BY facility_alias_id, group_type_id, product_id, po_date_month; 2018-06-20 19:06:27 UTC [1955]: [241-1] user=,db=,app=,client= LOG: terminating any other active server processes 2018-06-20 19:06:27 UTC [1955]: [242-1] user=,db=,app=,client= DEBUG: sending SIGQUIT to process 2834 2018-06-20 19:06:27 UTC [1955]: [243-1] user=,db=,app=,client= DEBUG: sending SIGQUIT to process 2830 2018-06-20 19:06:27 UTC [1955]: [244-1] user=,db=,app=,client= DEBUG: sending SIGQUIT to process 2829 2018-06-20 19:06:27 UTC [1955]: [245-1] user=,db=,app=,client= DEBUG: sending SIGQUIT to process 2831 2018-06-20 19:06:27 UTC [1955]: [246-1] user=,db=,app=,client= DEBUG: sending SIGQUIT to process 2832 2018-06-20 19:06:27 UTC [1955]: [247-1] user=,db=,app=,client= DEBUG: sending SIGQUIT to process 2833 2018-06-20 19:06:27 UTC [2834]: [10-1] user=,db=,app=,client= DEBUG: shmem_exit(-1): 0 before_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2834]: [11-1] user=,db=,app=,client= DEBUG: shmem_exit(-1): 0 on_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2834]: [12-1] user=,db=,app=,client= DEBUG: proc_exit(-1): 0 callbacks to make 2018-06-20 19:06:27 UTC [2830]: [3-1] user=,db=,app=,client= DEBUG: shmem_exit(-1): 0 before_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2830]: [4-1] user=,db=,app=,client= DEBUG: shmem_exit(-1): 0 on_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2830]: [5-1] user=,db=,app=,client= DEBUG: proc_exit(-1): 0 callbacks to make 2018-06-20 19:06:27 UTC [2829]: [2-1] user=,db=,app=,client= DEBUG: shmem_exit(-1): 0 before_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2829]: [3-1] user=,db=,app=,client= DEBUG: shmem_exit(-1): 0 on_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2829]: [4-1] user=,db=,app=,client= DEBUG: proc_exit(-1): 0 callbacks to make 2018-06-20 19:06:27 UTC [2831]: [1-1] user=,db=,app=,client= DEBUG: shmem_exit(-1): 0 before_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2831]: [2-1] user=,db=,app=,client= DEBUG: shmem_exit(-1): 0 on_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2831]: [3-1] user=,db=,app=,client= DEBUG: proc_exit(-1): 0 callbacks to make 2018-06-20 19:06:27 UTC [2832]: [8-1] user=,db=,app=,client= WARNING: terminating connection because of crash of another server process 2018-06-20 19:06:27 UTC [2832]: [9-1] user=,db=,app=,client= DETAIL: The postmaster has commanded this server process to roll back the current transaction and exit, because another server process exited abnormally and possibly corrupted shared memory. 2018-06-20 19:06:27 UTC [2832]: [10-1] user=,db=,app=,client= HINT: In a moment you should be able to reconnect to the database and repeat your command. 2018-06-20 19:06:27 UTC [2832]: [11-1] user=,db=,app=,client= DEBUG: shmem_exit(-1): 0 before_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2832]: [12-1] user=,db=,app=,client= DEBUG: shmem_exit(-1): 0 on_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2832]: [13-1] user=,db=,app=,client= DEBUG: proc_exit(-1): 0 callbacks to make 2018-06-20 19:06:27 UTC [2833]: [3-1] user=,db=,app=,client= DEBUG: writing stats file "pg_stat/global.stat" 2018-06-20 19:06:27 UTC [2833]: [4-1] user=,db=,app=,client= DEBUG: writing stats file "pg_stat/db_41044.stat" 2018-06-20 19:06:27 UTC [2833]: [5-1] user=,db=,app=,client= DEBUG: removing temporary stats file "pg_stat_tmp/db_41044.stat" 2018-06-20 19:06:27 UTC [2833]: [6-1] user=,db=,app=,client= DEBUG: writing stats file "pg_stat/db_0.stat" 2018-06-20 19:06:27 UTC [2833]: [7-1] user=,db=,app=,client= DEBUG: removing temporary stats file "pg_stat_tmp/db_0.stat" 2018-06-20 19:06:27 UTC [2833]: [8-1] user=,db=,app=,client= DEBUG: shmem_exit(-1): 0 before_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2833]: [9-1] user=,db=,app=,client= DEBUG: shmem_exit(-1): 0 on_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2833]: [10-1] user=,db=,app=,client= DEBUG: proc_exit(-1): 0 callbacks to make 2018-06-20 19:06:27 UTC [1955]: [248-1] user=,db=,app=,client= DEBUG: reaping dead processes 2018-06-20 19:06:27 UTC [1955]: [249-1] user=,db=,app=,client= DEBUG: forked new backend, pid=2865 socket=11 2018-06-20 19:06:27 UTC [1955]: [250-1] user=,db=,app=,client= DEBUG: reaping dead processes 2018-06-20 19:06:27 UTC [1955]: [251-1] user=,db=,app=,client= DEBUG: reaping dead processes 2018-06-20 19:06:27 UTC [2865]: [1-1] user=[unknown],db=[unknown],app=[unknown],client=localhost LOG: connection received: host=localhost port=37224 2018-06-20 19:06:27 UTC [2865]: [2-1] user=[unknown],db=[unknown],app=[unknown],client=localhost DEBUG: SSL connection from "(anonymous)" 2018-06-20 19:06:27 UTC [2865]: [3-1] user=postgres,db=procured,app=[unknown],client=localhost FATAL: the database system is in recovery mode 2018-06-20 19:06:27 UTC [2865]: [4-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: shmem_exit(1): 0 before_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2865]: [5-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: shmem_exit(1): 0 on_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2865]: [6-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: proc_exit(1): 1 callbacks to make 2018-06-20 19:06:27 UTC [2865]: [7-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: SSL: write alert (0x0100) 2018-06-20 19:06:27 UTC [2865]: [8-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: exit(1) 2018-06-20 19:06:27 UTC [2865]: [9-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: shmem_exit(-1): 0 before_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2865]: [10-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: shmem_exit(-1): 0 on_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2865]: [11-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: proc_exit(-1): 0 callbacks to make 2018-06-20 19:06:27 UTC [1955]: [252-1] user=,db=,app=,client= DEBUG: reaping dead processes 2018-06-20 19:06:27 UTC [1955]: [253-1] user=,db=,app=,client= DEBUG: server process (PID 2865) exited with exit code 1 2018-06-20 19:06:27 UTC [1955]: [254-1] user=,db=,app=,client= LOG: all server processes terminated; reinitializing 2018-06-20 19:06:27 UTC [1955]: [255-1] user=,db=,app=,client= DEBUG: shmem_exit(1): 0 before_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [1955]: [256-1] user=,db=,app=,client= DEBUG: shmem_exit(1): 5 on_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [1955]: [257-1] user=,db=,app=,client= DEBUG: cleaning up dynamic shared memory control segment with ID 1402578137 2018-06-20 19:06:27 UTC [1955]: [258-1] user=,db=,app=,client= DEBUG: invoking IpcMemoryCreate(size=571162624) 2018-06-20 19:06:27 UTC [1955]: [259-1] user=,db=,app=,client= DEBUG: SlruScanDirectory invoking callback on pg_notify/0000 2018-06-20 19:06:27 UTC [1955]: [260-1] user=,db=,app=,client= DEBUG: removing file "pg_notify/0000" 2018-06-20 19:06:27 UTC [1955]: [261-1] user=,db=,app=,client= DEBUG: dynamic shared memory system will support 132 segments 2018-06-20 19:06:27 UTC [1955]: [262-1] user=,db=,app=,client= DEBUG: created dynamic shared memory control segment 1058862587 (3184 bytes) 2018-06-20 19:06:27 UTC [1955]: [263-1] user=,db=,app=,client= DEBUG: forked new backend, pid=2867 socket=11 2018-06-20 19:06:27 UTC [2866]: [1-1] user=,db=,app=,client= LOG: database system was interrupted; last known up at 2018-06-20 19:05:34 UTC 2018-06-20 19:06:27 UTC [2867]: [1-1] user=[unknown],db=[unknown],app=[unknown],client=localhost LOG: connection received: host=localhost port=37226 2018-06-20 19:06:27 UTC [2867]: [2-1] user=postgres,db=procured,app=[unknown],client=localhost FATAL: the database system is in recovery mode 2018-06-20 19:06:27 UTC [2867]: [3-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: shmem_exit(1): 0 before_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2867]: [4-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: shmem_exit(1): 0 on_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2867]: [5-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: proc_exit(1): 1 callbacks to make 2018-06-20 19:06:27 UTC [2867]: [6-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: exit(1) 2018-06-20 19:06:27 UTC [2867]: [7-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: shmem_exit(-1): 0 before_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2867]: [8-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: shmem_exit(-1): 0 on_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2867]: [9-1] user=postgres,db=procured,app=[unknown],client=localhost DEBUG: proc_exit(-1): 0 callbacks to make 2018-06-20 19:06:27 UTC [1955]: [264-1] user=,db=,app=,client= DEBUG: reaping dead processes 2018-06-20 19:06:27 UTC [1955]: [265-1] user=,db=,app=,client= DEBUG: server process (PID 2867) exited with exit code 1 2018-06-20 19:06:27 UTC [2866]: [2-1] user=,db=,app=,client= DEBUG: checkpoint record is at 0/72FBC018 2018-06-20 19:06:27 UTC [2866]: [3-1] user=,db=,app=,client= DEBUG: redo record is at 0/72FBC018; shutdown TRUE 2018-06-20 19:06:27 UTC [2866]: [4-1] user=,db=,app=,client= DEBUG: next transaction ID: 0:782; next OID: 49152 2018-06-20 19:06:27 UTC [2866]: [5-1] user=,db=,app=,client= DEBUG: next MultiXactId: 1; next MultiXactOffset: 0 2018-06-20 19:06:27 UTC [2866]: [6-1] user=,db=,app=,client= DEBUG: oldest unfrozen transaction ID: 548, in database 1 2018-06-20 19:06:27 UTC [2866]: [7-1] user=,db=,app=,client= DEBUG: oldest MultiXactId: 1, in database 1 2018-06-20 19:06:27 UTC [2866]: [8-1] user=,db=,app=,client= DEBUG: commit timestamp Xid oldest/newest: 555/780 2018-06-20 19:06:27 UTC [2866]: [9-1] user=,db=,app=,client= DEBUG: transaction ID wrap limit is 2147484195, limited by database with OID 1 2018-06-20 19:06:27 UTC [2866]: [10-1] user=,db=,app=,client= DEBUG: MultiXactId wrap limit is 2147483648, limited by database with OID 1 2018-06-20 19:06:27 UTC [2866]: [11-1] user=,db=,app=,client= DEBUG: starting up replication slots 2018-06-20 19:06:27 UTC [2866]: [12-1] user=,db=,app=,client= DEBUG: starting up replication origin progress state 2018-06-20 19:06:27 UTC [2866]: [13-1] user=,db=,app=,client= LOG: database system was not properly shut down; automatic recovery in progress 2018-06-20 19:06:27 UTC [2866]: [14-1] user=,db=,app=,client= DEBUG: resetting unlogged relations: cleanup 1 init 0 2018-06-20 19:06:27 UTC [2866]: [15-1] user=,db=,app=,client= LOG: redo starts at 0/72FBC088 2018-06-20 19:06:27 UTC [2866]: [16-1] user=,db=,app=,client= LOG: redo done at 0/72FFC8A0 2018-06-20 19:06:27 UTC [2866]: [17-1] user=,db=,app=,client= DEBUG: resetting unlogged relations: cleanup 0 init 1 2018-06-20 19:06:27 UTC [2866]: [18-1] user=,db=,app=,client= LOG: checkpoint starting: end-of-recovery immediate 2018-06-20 19:06:27 UTC [2866]: [19-1] user=,db=,app=,client= DEBUG: performing replication slot checkpoint 2018-06-20 19:06:27 UTC [2866]: [20-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=1 file=base/41044/2608 time=1.235 msec 2018-06-20 19:06:27 UTC [2866]: [21-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=2 file=base/41044/2704 time=0.056 msec 2018-06-20 19:06:27 UTC [2866]: [22-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=3 file=base/41044/1247 time=0.059 msec 2018-06-20 19:06:27 UTC [2866]: [23-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=4 file=base/41044/2692 time=0.058 msec 2018-06-20 19:06:27 UTC [2866]: [24-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=5 file=base/41044/2610 time=0.050 msec 2018-06-20 19:06:27 UTC [2866]: [25-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=6 file=base/41044/2673 time=0.050 msec 2018-06-20 19:06:27 UTC [2866]: [26-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=7 file=base/41044/41255 time=0.050 msec 2018-06-20 19:06:27 UTC [2866]: [27-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=8 file=base/41044/1259 time=0.049 msec 2018-06-20 19:06:27 UTC [2866]: [28-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=9 file=base/41044/2679 time=0.050 msec 2018-06-20 19:06:27 UTC [2866]: [29-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=10 file=base/41044/2658 time=0.049 msec 2018-06-20 19:06:27 UTC [2866]: [30-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=11 file=base/41044/1249 time=0.049 msec 2018-06-20 19:06:27 UTC [2866]: [31-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=12 file=base/41044/2678 time=0.046 msec 2018-06-20 19:06:27 UTC [2866]: [32-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=13 file=base/41044/3455 time=0.053 msec 2018-06-20 19:06:27 UTC [2866]: [33-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=14 file=base/41044/1255 time=0.050 msec 2018-06-20 19:06:27 UTC [2866]: [34-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=15 file=base/41044/2703 time=0.049 msec 2018-06-20 19:06:27 UTC [2866]: [35-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=16 file=base/41044/2838 time=0.051 msec 2018-06-20 19:06:27 UTC [2866]: [36-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=17 file=base/41044/49157 time=0.049 msec 2018-06-20 19:06:27 UTC [2866]: [37-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=18 file=base/41044/2693 time=0.061 msec 2018-06-20 19:06:27 UTC [2866]: [38-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=19 file=base/41044/2659 time=0.056 msec 2018-06-20 19:06:27 UTC [2866]: [39-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=20 file=base/41044/2662 time=0.047 msec 2018-06-20 19:06:27 UTC [2866]: [40-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=21 file=base/41044/2663 time=0.049 msec 2018-06-20 19:06:27 UTC [2866]: [41-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=22 file=base/41044/2674 time=0.049 msec 2018-06-20 19:06:27 UTC [2866]: [42-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=23 file=base/41044/2839 time=0.050 msec 2018-06-20 19:06:27 UTC [2866]: [43-1] user=,db=,app=,client= DEBUG: checkpoint sync: number=24 file=base/41044/2618 time=0.052 msec 2018-06-20 19:06:27 UTC [2866]: [44-1] user=,db=,app=,client= DEBUG: attempting to remove WAL segments older than log file 000000000000000000000000 2018-06-20 19:06:27 UTC [2866]: [45-1] user=,db=,app=,client= LOG: checkpoint complete: wrote 46 buffers (0.1%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.002 s, total=0.009 s; sync files=24, longest=0.001 s, average=0.000 s; distance=266 kB, estimate=266 kB 2018-06-20 19:06:27 UTC [2866]: [46-1] user=,db=,app=,client= DEBUG: MultiXactId wrap limit is 2147483648, limited by database with OID 1 2018-06-20 19:06:27 UTC [2866]: [47-1] user=,db=,app=,client= DEBUG: MultiXact member stop limit is now 4294914944 based on MultiXact 1 2018-06-20 19:06:27 UTC [2866]: [48-1] user=,db=,app=,client= DEBUG: shmem_exit(0): 1 before_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2866]: [49-1] user=,db=,app=,client= DEBUG: shmem_exit(0): 4 on_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2866]: [50-1] user=,db=,app=,client= DEBUG: proc_exit(0): 2 callbacks to make 2018-06-20 19:06:27 UTC [2866]: [51-1] user=,db=,app=,client= DEBUG: exit(0) 2018-06-20 19:06:27 UTC [2866]: [52-1] user=,db=,app=,client= DEBUG: shmem_exit(-1): 0 before_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2866]: [53-1] user=,db=,app=,client= DEBUG: shmem_exit(-1): 0 on_shmem_exit callbacks to make 2018-06-20 19:06:27 UTC [2866]: [54-1] user=,db=,app=,client= DEBUG: proc_exit(-1): 0 callbacks to make 2018-06-20 19:06:27 UTC [1955]: [266-1] user=,db=,app=,client= DEBUG: reaping dead processes 2018-06-20 19:06:27 UTC [2868]: [1-1] user=,db=,app=,client= DEBUG: checkpointer updated shared memory configuration values 2018-06-20 19:06:27 UTC [2871]: [1-1] user=,db=,app=,client= DEBUG: autovacuum launcher started 2018-06-20 19:06:27 UTC [2871]: [2-1] user=,db=,app=,client= DEBUG: InitPostgres 2018-06-20 19:06:27 UTC [1955]: [267-1] user=,db=,app=,client= DEBUG: starting background worker process "logical replication launcher" 2018-06-20 19:06:27 UTC [2871]: [3-1] user=,db=,app=,client= DEBUG: my backend ID is 1 2018-06-20 19:06:27 UTC [1955]: [268-1] user=,db=,app=,client= LOG: database system is ready to accept connections 2018-06-20 19:06:27 UTC [2871]: [4-1] user=,db=,app=,client= DEBUG: StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0 2018-06-20 19:06:27 UTC [2871]: [5-1] user=,db=,app=,client= DEBUG: CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGR, xid/subid/cid: 0/1/0 2018-06-20 19:06:27 UTC [2872]: [1-1] user=,db=,app=,client= DEBUG: received inquiry for database 0 2018-06-20 19:06:27 UTC [2872]: [2-1] user=,db=,app=,client= DEBUG: writing stats file "pg_stat_tmp/global.stat" 2018-06-20 19:06:27 UTC [2873]: [1-1] user=,db=,app=,client= DEBUG: logical replication launcher started 2018-06-20 19:06:27 UTC [2873]: [2-1] user=,db=,app=,client= DEBUG: InitPostgres 2018-06-20 19:06:27 UTC [2873]: [3-1] user=,db=,app=,client= DEBUG: my backend ID is 2 2018-06-20 19:06:27 UTC [2873]: [4-1] user=,db=,app=,client= DEBUG: StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0 2018-06-20 19:06:27 UTC [2873]: [5-1] user=,db=,app=,client= DEBUG: CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGR, xid/subid/cid: 0/1/0 2018-06-20 19:06:27 UTC [2873]: [6-1] user=,db=,app=,client= DEBUG: StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0 2018-06-20 19:06:27 UTC [2873]: [7-1] user=,db=,app=,client= DEBUG: CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGR, xid/subid/cid: 0/1/0 2018-06-20 19:06:27 UTC [2873]: [8-1] user=,db=,app=,client= DEBUG: StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0 2018-06-20 19:06:27 UTC [2873]: [9-1] user=,db=,app=,client= DEBUG: CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGR, xid/subid/cid: 0/1/0 2018-06-20 19:06:27 UTC [2871]: [6-1] user=,db=,app=,client= DEBUG: StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0 2018-06-20 19:06:27 UTC [2871]: [7-1] user=,db=,app=,client= DEBUG: CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGR, xid/subid/cid: 0/1/0 2018-06-20 19:06:42 UTC [2869]: [1-1] user=,db=,app=,client= DEBUG: snapshot of 0+0 running transaction ids (lsn 0/72FFE900 oldest xid 783 latest complete 782 next xid 783)
Attachment:
postgres_10_4_crash_schema.sql
Description: Binary data
