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)
postgres_10_4_crash_schema.sql
Description: Binary data
