Strange problem starting pbs

Hello,
I’ve read some thread that looks like my problem but found no solution in it.

I upgrade my pbs server my old configuration was pbspro 18 … with debian 11 and postgres
psql (PostgreSQL) 13.7 (Debian 13.7-0+deb11u1)

I make a new VM debian 11 too … and compile openpbs 20 all works fine
I’ve my deb packages

I install postgres 13.7 , install openpbs-server too …

I can contact postgres , I can launch pbs_comm, pbs_mom, pbs_sched … but when
pbs_server comes … postgres shutdown !!

I start manually
/usr/lib/postgresql/13/bin/postgres -d5 -D /var/spool/pbs/datastore -p 15007 &

launch pbs with

service pbs restart

then postgresql is shutdown …

I try the same operation on my PBSpro instance … and it works well …

I’ve no firewall rules ,

the only difference was IP address and name … that I’ve changed :slight_smile: obviously

# the previous pbsserver PBSpro
xx.yy.40.33     I2BC-PBS.calcul.fr    I2BC-PBS
xx.yy.39.19     pbsserver.example.org pbsserver
# the next one openPBS
xx.yy.40.35     I2BC-PBS-tmp.calcul.fr        I2BC-PBStmp
xx.yy.39.21     PBSServer-tmp.calcul.fr       pbsserver-tmp

my pbs.conf is

PBS_EXEC=/usr/local
PBS_SERVER=pbsserver-tmp
PBS_START_SERVER=1
PBS_START_SCHED=1
PBS_START_COMM=1
PBS_START_MOM=0
PBS_HOME=/var/spool/pbs
PBS_CORE_LIMIT=unlimited
PBS_SCP=/usr/bin/scp

I don’t understand the problem :slight_smile:

Best Regards

Could you please share the postgres logs and pbs server logs

Hi,
I made it this morning ( 9:25 … France :slight_smile: )
restart with “service pbs restart” command
pbs server_log

07/21/2022 09:25:23;0002;Server@pbsserver-tmp;Svr;Log;Log opened
07/21/2022 09:25:23;0002;Server@pbsserver-tmp;Svr;Server@pbsserver-tmp;pbs_version=20.0.0
07/21/2022 09:25:23;0002;Server@pbsserver-tmp;Svr;Server@pbsserver-tmp;pbs_build=mach=N/A:security=N/A:configure_args=N/A
07/21/2022 09:25:23;0002;Server@pbsserver-tmp;Svr;Server@pbsserver-tmp;hostname=pbsserver-tmp.calcul.i2bc.paris-saclay.fr;pbs_leaf_name=N/A;pbs_mom_node_name=N/A
07/21/2022 09:25:23;0002;Server@pbsserver-tmp;Svr;Server@pbsserver-tmp;ipv4 interface lo: localhost 
07/21/2022 09:25:23;0002;Server@pbsserver-tmp;Svr;Server@pbsserver-tmp;ipv4 interface ens192: I2BC-PBStmp 
07/21/2022 09:25:23;0002;Server@pbsserver-tmp;Svr;Server@pbsserver-tmp;ipv4 interface ens224: pbsserver-tmp 
07/21/2022 09:25:23;0002;Server@pbsserver-tmp;Svr;Server@pbsserver-tmp;ipv6 interface lo: ip6-loopback 
07/21/2022 09:25:23;0006;Server@pbsserver-tmp;Fil;Server@pbsserver-tmp;Version 20.0.0, started, initialization type = 1
07/21/2022 09:25:23;0002;Server@pbsserver-tmp;Svr;Server@pbsserver-tmp;pbs_status_db exit code 1
07/21/2022 09:25:23;0002;Server@pbsserver-tmp;Svr;Server@pbsserver-tmp;Starting PBS dataservice
07/21/2022 09:25:27;0002;Server@pbsserver-tmp;Svr;Server@pbsserver-tmp;connected to PBS dataservice@pbsserver-tmp.calcul.fr
07/21/2022 09:25:27;0d80;Server@pbsserver-tmp;TPP;Server@pbsserver-tmp(Main Thread);TPP authentication method = resvport
07/21/2022 09:25:27;0c06;Server@pbsserver-tmp;TPP;Server@pbsserver-tmp(Main Thread);TPP leaf node names = 10.95.39.21:15001,127.0.0.1:15001,xx.yy.40.35:15001,xx.yy.39.21:15001
07/21/2022 09:25:27;0d80;Server@pbsserver-tmp;TPP;Server@pbsserver-tmp(Main Thread);Initializing TPP transport Layer
07/21/2022 09:25:27;0d80;Server@pbsserver-tmp;TPP;Server@pbsserver-tmp(Main Thread);Max files allowed = 1024
07/21/2022 09:25:27;0c06;Server@pbsserver-tmp;TPP;Server@pbsserver-tmp(Main Thread);Max files too low - you may want to increase it.
07/21/2022 09:25:27;0d80;Server@pbsserver-tmp;TPP;Server@pbsserver-tmp(Main Thread);TPP initialization done
07/21/2022 09:25:27;0d80;Server@pbsserver-tmp;TPP;Server@pbsserver-tmp(Main Thread);Connecting to pbs_comm pbsserver-tmp:17001
07/21/2022 09:25:27;0c06;Server@pbsserver-tmp;TPP;Server@pbsserver-tmp(Thread 0);Thread ready
07/21/2022 09:25:27;0c06;Server@pbsserver-tmp;TPP;Server@pbsserver-tmp(Thread 0);Registering address 10.95.39.21:15001 to pbs_comm pbsserver-tmp:17001
07/21/2022 09:25:27;0c06;Server@pbsserver-tmp;TPP;Server@pbsserver-tmp(Thread 0);Registering address 10.95.40.35:15001 to pbs_comm pbsserver-tmp:17001
07/21/2022 09:25:27;0c06;Server@pbsserver-tmp;TPP;Server@pbsserver-tmp(Thread 0);Connected to pbs_comm pbsserver-tmp:17001
07/21/2022 09:25:27;0002;Server@pbsserver-tmp;n/a;setup_env;read environment from /var/spool/pbs/pbs_environment
07/21/2022 09:25:27;0000;Server@pbsserver-tmp;Svr;Server@pbsserver-tmp;Supported authentication method: resvport
07/21/2022 09:25:27;0002;Server@pbsserver-tmp;Svr;Server@pbsserver-tmp;Stopping PBS dataservice

And postgres log

2022-07-21 09:25:24.036 CEST [32672] LOG:  starting PostgreSQL 13.7 (Debian 13.7-0+deb11u1) on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2022-07-21 09:25:24.036 CEST [32672] LOG:  listening on IPv4 address "0.0.0.0", port 15007
2022-07-21 09:25:24.036 CEST [32672] LOG:  listening on IPv6 address "::", port 15007
2022-07-21 09:25:24.040 CEST [32672] LOG:  listening on Unix socket "/var/run/postgresql/.s.PGSQL.15007"
2022-07-21 09:25:24.050 CEST [32684] LOG:  database system was shut down at 2022-07-20 17:14:32 CEST
2022-07-21 09:25:24.056 CEST [32672] LOG:  database system is ready to accept connections
2022-07-21 09:25:27.144 CEST [32672] LOG:  received fast shutdown request
2022-07-21 09:25:27.148 CEST [32672] LOG:  aborting any active transactions
2022-07-21 09:25:27.149 CEST [32672] LOG:  background worker "logical replication launcher" (PID 32690) exited with exit code 1
2022-07-21 09:25:27.149 CEST [32685] LOG:  shutting down
2022-07-21 09:25:27.171 CEST [32672] LOG:  database system is shut down

And the log when postgres is running before a “service pbs restart”

/usr/lib/postgresql/13/bin/postgres -d5 -D /var/spool/pbs/datastore -p 15007 &

pbs server_log is the same …

postgres with debug 5

2022-07-21 09:30:01.123 CEST [32793] LOG:  starting PostgreSQL 13.7 (Debian 13.7-0+deb11u1) on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2022-07-21 09:30:01.123 CEST [32793] LOG:  listening on IPv4 address "0.0.0.0", port 15007
2022-07-21 09:30:01.123 CEST [32793] LOG:  listening on IPv6 address "::", port 15007
2022-07-21 09:30:01.126 CEST [32793] LOG:  listening on Unix socket "/var/run/postgresql/.s.PGSQL.15007"
2022-07-21 09:30:01.132 CEST [32795] LOG:  database system was shut down at 2022-07-21 09:25:27 CEST
2022-07-21 09:30:01.132 CEST [32795] DEBUG:  checkpoint record is at 0/1690408
2022-07-21 09:30:01.132 CEST [32795] DEBUG:  redo record is at 0/1690408; shutdown true
2022-07-21 09:30:01.132 CEST [32795] DEBUG:  next transaction ID: 503; next OID: 16584
2022-07-21 09:30:01.132 CEST [32795] DEBUG:  next MultiXactId: 1; next MultiXactOffset: 0
2022-07-21 09:30:01.132 CEST [32795] DEBUG:  oldest unfrozen transaction ID: 478, in database 1
2022-07-21 09:30:01.133 CEST [32795] DEBUG:  oldest MultiXactId: 1, in database 1
2022-07-21 09:30:01.133 CEST [32795] DEBUG:  commit timestamp Xid oldest/newest: 0/0
2022-07-21 09:30:01.133 CEST [32795] DEBUG:  transaction ID wrap limit is 2147484125, limited by database with OID 1
2022-07-21 09:30:01.133 CEST [32795] DEBUG:  MultiXactId wrap limit is 2147483648, limited by database with OID 1
2022-07-21 09:30:01.133 CEST [32795] DEBUG:  starting up replication slots
2022-07-21 09:30:01.133 CEST [32795] DEBUG:  starting up replication origin progress state
2022-07-21 09:30:01.133 CEST [32795] DEBUG:  MultiXactId wrap limit is 2147483648, limited by database with OID 1
2022-07-21 09:30:01.133 CEST [32795] DEBUG:  MultiXact member stop limit is now 4294914944 based on MultiXact 1
2022-07-21 09:30:01.136 CEST [32795] DEBUG:  shmem_exit(0): 1 before_shmem_exit callbacks to make
2022-07-21 09:30:01.136 CEST [32795] DEBUG:  shmem_exit(0): 6 on_shmem_exit callbacks to make
2022-07-21 09:30:01.136 CEST [32795] DEBUG:  proc_exit(0): 2 callbacks to make
2022-07-21 09:30:01.136 CEST [32795] DEBUG:  exit(0)
2022-07-21 09:30:01.136 CEST [32795] DEBUG:  shmem_exit(-1): 0 before_shmem_exit callbacks to make
2022-07-21 09:30:01.136 CEST [32795] DEBUG:  shmem_exit(-1): 0 on_shmem_exit callbacks to make
2022-07-21 09:30:01.136 CEST [32795] DEBUG:  proc_exit(-1): 0 callbacks to make
2022-07-21 09:30:01.137 CEST [32793] DEBUG:  reaping dead processes
2022-07-21 09:30:01.137 CEST [32796] DEBUG:  checkpointer updated shared memory configuration values
2022-07-21 09:30:01.138 CEST [32799] DEBUG:  autovacuum launcher started
2022-07-21 09:30:01.138 CEST [32793] DEBUG:  starting background worker process "logical replication launcher"
2022-07-21 09:30:01.138 CEST [32799] DEBUG:  InitPostgres
2022-07-21 09:30:01.138 CEST [32799] DEBUG:  my backend ID is 1
2022-07-21 09:30:01.138 CEST [32793] LOG:  database system is ready to accept connections
2022-07-21 09:30:01.138 CEST [32800] DEBUG:  removing permanent stats file "pg_stat/db_16384.stat"
2022-07-21 09:30:01.138 CEST [32799] DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:30:01.139 CEST [32800] DEBUG:  removing permanent stats file "pg_stat/db_13445.stat"
2022-07-21 09:30:01.139 CEST [32800] DEBUG:  removing permanent stats file "pg_stat/db_0.stat"
2022-07-21 09:30:01.139 CEST [32800] DEBUG:  removing permanent stats file "pg_stat/global.stat"
2022-07-21 09:30:01.139 CEST [32799] DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:30:01.139 CEST [32800] DEBUG:  received inquiry for database 0
2022-07-21 09:30:01.139 CEST [32800] DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2022-07-21 09:30:01.139 CEST [32800] DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2022-07-21 09:30:01.139 CEST [32801] DEBUG:  logical replication launcher started
2022-07-21 09:30:01.139 CEST [32801] DEBUG:  InitPostgres
2022-07-21 09:30:01.139 CEST [32801] DEBUG:  my backend ID is 2
2022-07-21 09:30:01.139 CEST [32801] DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:30:01.139 CEST [32801] DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:30:01.139 CEST [32801] DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:30:01.139 CEST [32801] DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:30:01.139 CEST [32801] DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:30:01.139 CEST [32801] DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:30:16.155 CEST [32797] DEBUG:  snapshot of 0+0 running transaction ids (lsn 0/16904B8 oldest xid 503 latest complete 502 next xid 503)
2022-07-21 09:30:31.178 CEST [32799] DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:30:31.178 CEST [32799] DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:30:31.178 CEST [32800] DEBUG:  received inquiry for database 0
2022-07-21 09:30:31.178 CEST [32800] DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2022-07-21 09:30:31.178 CEST [32800] DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2022-07-21 09:30:31.190 CEST [32802] DEBUG:  InitPostgres
2022-07-21 09:30:31.190 CEST [32802] DEBUG:  my backend ID is 3
2022-07-21 09:30:31.190 CEST [32802] DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:30:31.194 CEST [32802] DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:30:31.194 CEST [32802] DEBUG:  autovacuum: processing database "pbs_datastore"
2022-07-21 09:30:31.194 CEST [32800] DEBUG:  received inquiry for database 16384
2022-07-21 09:30:31.194 CEST [32800] DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2022-07-21 09:30:31.194 CEST [32800] DEBUG:  writing stats file "pg_stat_tmp/db_16384.stat"
2022-07-21 09:30:31.194 CEST [32800] DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  info: vac: 0 (threshold 50), ins: 1 (threshold 1000), anl: 1 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  server: vac: 0 (threshold 50), ins: 0 (threshold 1000), anl: 0 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  scheduler: vac: 0 (threshold 50), ins: 0 (threshold 1000), anl: 0 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  node: vac: 0 (threshold 50), ins: 0 (threshold 1000), anl: 0 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  queue: vac: 0 (threshold 50), ins: 0 (threshold 1000), anl: 0 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  resv: vac: 0 (threshold 50), ins: 0 (threshold 1000), anl: 0 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  job: vac: 0 (threshold 50), ins: 0 (threshold 1000), anl: 0 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  job_scr: vac: 0 (threshold 50), ins: 0 (threshold 1000), anl: 0 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_type: vac: 2 (threshold 132), ins: 30 (threshold 1082), anl: 32 (threshold 91)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_authid: vac: 1 (threshold 52), ins: 0 (threshold 1002), anl: 1 (threshold 51)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_attribute: vac: 0 (threshold 622), ins: 210 (threshold 1572), anl: 216 (threshold 336)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_proc: vac: 0 (threshold 657), ins: 59 (threshold 1607), anl: 59 (threshold 353)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_class: vac: 1 (threshold 127), ins: 34 (threshold 1077), anl: 42 (threshold 89)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_attrdef: vac: 0 (threshold 50), ins: 6 (threshold 1000), anl: 6 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_constraint: vac: 0 (threshold 50), ins: 5 (threshold 1000), anl: 5 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_index: vac: 0 (threshold 81), ins: 17 (threshold 1031), anl: 17 (threshold 65)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_operator: vac: 15 (threshold 207), ins: 22 (threshold 1157), anl: 44 (threshold 128)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_opfamily: vac: 0 (threshold 72), ins: 4 (threshold 1022), anl: 4 (threshold 61)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_opclass: vac: 0 (threshold 76), ins: 4 (threshold 1026), anl: 4 (threshold 63)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_am: vac: 0 (threshold 51), ins: 0 (threshold 1001), anl: 0 (threshold 51)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_amop: vac: 0 (threshold 195), ins: 15 (threshold 1145), anl: 15 (threshold 122)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_amproc: vac: 0 (threshold 147), ins: 15 (threshold 1097), anl: 15 (threshold 98)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_language: vac: 0 (threshold 51), ins: 0 (threshold 1001), anl: 0 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_description: vac: 0 (threshold 1024), ins: 1 (threshold 1974), anl: 1 (threshold 537)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_cast: vac: 0 (threshold 95), ins: 3 (threshold 1045), anl: 3 (threshold 72)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_namespace: vac: 0 (threshold 51), ins: 1 (threshold 1001), anl: 1 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_depend: vac: 50 (threshold 1595), ins: 540 (threshold 2545), anl: 590 (threshold 822)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_database: vac: 0 (threshold 50), ins: 1 (threshold 1000), anl: 1 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_tablespace: vac: 0 (threshold 50), ins: 0 (threshold 1000), anl: 0 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_shdepend: vac: 0 (threshold 52), ins: 0 (threshold 1002), anl: 0 (threshold 51)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_extension: vac: 3 (threshold 50), ins: 1 (threshold 1000), anl: 4 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_toast_16513: vac: 0 (threshold 50), ins: 0 (threshold 1000), anl: 0 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_toast_16519: vac: 0 (threshold 50), ins: 0 (threshold 1000), anl: 0 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_toast_16526: vac: 0 (threshold 50), ins: 0 (threshold 1000), anl: 0 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_toast_16538: vac: 0 (threshold 50), ins: 0 (threshold 1000), anl: 0 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_toast_16548: vac: 0 (threshold 50), ins: 0 (threshold 1000), anl: 0 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_toast_16558: vac: 0 (threshold 50), ins: 0 (threshold 1000), anl: 0 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_toast_16567: vac: 0 (threshold 50), ins: 0 (threshold 1000), anl: 0 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  pg_toast_16577: vac: 0 (threshold 50), ins: 0 (threshold 1000), anl: 0 (threshold 50)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  shmem_exit(0): 1 before_shmem_exit callbacks to make
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  shmem_exit(0): 7 on_shmem_exit callbacks to make
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  proc_exit(0): 2 callbacks to make
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  exit(0)
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  shmem_exit(-1): 0 before_shmem_exit callbacks to make
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  shmem_exit(-1): 0 on_shmem_exit callbacks to make
2022-07-21 09:30:31.204 CEST [32802] DEBUG:  proc_exit(-1): 0 callbacks to make
2022-07-21 09:30:31.205 CEST [32793] DEBUG:  reaping dead processes
2022-07-21 09:30:31.205 CEST [32793] DEBUG:  server process (PID 32802) exited with exit code 0
2022-07-21 09:31:01.178 CEST [32799] DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:31:01.178 CEST [32799] DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:31:01.178 CEST [32800] DEBUG:  received inquiry for database 0
2022-07-21 09:31:01.178 CEST [32800] DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2022-07-21 09:31:01.179 CEST [32800] DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2022-07-21 09:31:01.190 CEST [32804] DEBUG:  InitPostgres
2022-07-21 09:31:01.190 CEST [32804] DEBUG:  my backend ID is 3
2022-07-21 09:31:01.190 CEST [32804] DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:31:01.193 CEST [32804] DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:31:01.193 CEST [32804] DEBUG:  autovacuum: processing database "postgres"
2022-07-21 09:31:01.193 CEST [32800] DEBUG:  received inquiry for database 13445
2022-07-21 09:31:01.194 CEST [32800] DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2022-07-21 09:31:01.194 CEST [32800] DEBUG:  writing stats file "pg_stat_tmp/db_13445.stat"
2022-07-21 09:31:01.194 CEST [32800] DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  pg_type: vac: 0 (threshold 132), ins: 0 (threshold 1082), anl: 0 (threshold 91)
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  pg_authid: vac: 1 (threshold 52), ins: 0 (threshold 1002), anl: 1 (threshold 51)
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  pg_attribute: vac: 0 (threshold 622), ins: 0 (threshold 1572), anl: 0 (threshold 336)
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  pg_proc: vac: 0 (threshold 657), ins: 0 (threshold 1607), anl: 0 (threshold 353)
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  pg_class: vac: 0 (threshold 127), ins: 0 (threshold 1077), anl: 0 (threshold 89)
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  pg_index: vac: 0 (threshold 81), ins: 0 (threshold 1031), anl: 0 (threshold 65)
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  pg_opclass: vac: 0 (threshold 76), ins: 0 (threshold 1026), anl: 0 (threshold 63)
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  pg_am: vac: 0 (threshold 51), ins: 0 (threshold 1001), anl: 0 (threshold 51)
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  pg_amproc: vac: 0 (threshold 147), ins: 0 (threshold 1097), anl: 0 (threshold 98)
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  pg_namespace: vac: 0 (threshold 51), ins: 0 (threshold 1001), anl: 0 (threshold 50)
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  pg_database: vac: 0 (threshold 50), ins: 1 (threshold 1000), anl: 1 (threshold 50)
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  pg_tablespace: vac: 0 (threshold 50), ins: 0 (threshold 1000), anl: 0 (threshold 50)
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  pg_shdepend: vac: 0 (threshold 52), ins: 0 (threshold 1002), anl: 0 (threshold 51)
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  shmem_exit(0): 1 before_shmem_exit callbacks to make
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  shmem_exit(0): 7 on_shmem_exit callbacks to make
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  proc_exit(0): 2 callbacks to make
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  exit(0)
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  shmem_exit(-1): 0 before_shmem_exit callbacks to make
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  shmem_exit(-1): 0 on_shmem_exit callbacks to make
2022-07-21 09:31:01.204 CEST [32804] DEBUG:  proc_exit(-1): 0 callbacks to make
2022-07-21 09:31:01.205 CEST [32793] DEBUG:  reaping dead processes
2022-07-21 09:31:01.205 CEST [32793] DEBUG:  server process (PID 32804) exited with exit code 0
2022-07-21 09:31:05.640 CEST [32793] DEBUG:  postmaster received signal 2
2022-07-21 09:31:05.640 CEST [32793] LOG:  received fast shutdown request
2022-07-21 09:31:05.697 CEST [32793] LOG:  aborting any active transactions
2022-07-21 09:31:05.697 CEST [32793] DEBUG:  sending signal 15 to process 32801
2022-07-21 09:31:05.697 CEST [32799] DEBUG:  autovacuum launcher shutting down
2022-07-21 09:31:05.697 CEST [32799] DEBUG:  shmem_exit(0): 1 before_shmem_exit callbacks to make
2022-07-21 09:31:05.697 CEST [32799] DEBUG:  shmem_exit(0): 6 on_shmem_exit callbacks to make
2022-07-21 09:31:05.697 CEST [32797] DEBUG:  shmem_exit(0): 1 before_shmem_exit callbacks to make
2022-07-21 09:31:05.697 CEST [32797] DEBUG:  shmem_exit(0): 5 on_shmem_exit callbacks to make
2022-07-21 09:31:05.697 CEST [32799] DEBUG:  proc_exit(0): 2 callbacks to make
2022-07-21 09:31:05.697 CEST [32799] DEBUG:  exit(0)
2022-07-21 09:31:05.697 CEST [32797] DEBUG:  proc_exit(0): 2 callbacks to make
2022-07-21 09:31:05.697 CEST [32799] DEBUG:  shmem_exit(-1): 0 before_shmem_exit callbacks to make
2022-07-21 09:31:05.697 CEST [32799] DEBUG:  shmem_exit(-1): 0 on_shmem_exit callbacks to make
2022-07-21 09:31:05.697 CEST [32797] DEBUG:  exit(0)
2022-07-21 09:31:05.697 CEST [32801] DEBUG:  logical replication launcher shutting down
2022-07-21 09:31:05.697 CEST [32799] DEBUG:  proc_exit(-1): 0 callbacks to make
2022-07-21 09:31:05.697 CEST [32797] DEBUG:  shmem_exit(-1): 0 before_shmem_exit callbacks to make
2022-07-21 09:31:05.697 CEST [32797] DEBUG:  shmem_exit(-1): 0 on_shmem_exit callbacks to make
2022-07-21 09:31:05.697 CEST [32801] DEBUG:  shmem_exit(1): 2 before_shmem_exit callbacks to make
2022-07-21 09:31:05.697 CEST [32797] DEBUG:  proc_exit(-1): 0 callbacks to make
2022-07-21 09:31:05.697 CEST [32801] DEBUG:  shmem_exit(1): 6 on_shmem_exit callbacks to make
2022-07-21 09:31:05.697 CEST [32801] DEBUG:  proc_exit(1): 2 callbacks to make
2022-07-21 09:31:05.697 CEST [32801] DEBUG:  exit(1)
2022-07-21 09:31:05.697 CEST [32798] DEBUG:  shmem_exit(0): 1 before_shmem_exit callbacks to make
2022-07-21 09:31:05.697 CEST [32801] DEBUG:  shmem_exit(-1): 0 before_shmem_exit callbacks to make
2022-07-21 09:31:05.697 CEST [32801] DEBUG:  shmem_exit(-1): 0 on_shmem_exit callbacks to make
2022-07-21 09:31:05.697 CEST [32798] DEBUG:  shmem_exit(0): 5 on_shmem_exit callbacks to make
2022-07-21 09:31:05.697 CEST [32801] DEBUG:  proc_exit(-1): 0 callbacks to make
2022-07-21 09:31:05.697 CEST [32798] DEBUG:  proc_exit(0): 2 callbacks to make
2022-07-21 09:31:05.697 CEST [32798] DEBUG:  exit(0)
2022-07-21 09:31:05.697 CEST [32798] DEBUG:  shmem_exit(-1): 0 before_shmem_exit callbacks to make
2022-07-21 09:31:05.697 CEST [32798] DEBUG:  shmem_exit(-1): 0 on_shmem_exit callbacks to make
2022-07-21 09:31:05.697 CEST [32798] DEBUG:  proc_exit(-1): 0 callbacks to make
2022-07-21 09:31:05.699 CEST [32793] DEBUG:  reaping dead processes
2022-07-21 09:31:05.699 CEST [32793] LOG:  background worker "logical replication launcher" (PID 32801) exited with exit code 1
2022-07-21 09:31:05.699 CEST [32793] DEBUG:  reaping dead processes
2022-07-21 09:31:05.699 CEST [32796] LOG:  shutting down
2022-07-21 09:31:05.707 CEST [32796] DEBUG:  performing replication slot checkpoint
2022-07-21 09:31:05.717 CEST [32796] DEBUG:  attempting to remove WAL segments older than log file 000000000000000000000000
2022-07-21 09:31:05.717 CEST [32796] DEBUG:  SlruScanDirectory invoking callback on pg_subtrans/0000
2022-07-21 09:31:05.719 CEST [32796] DEBUG:  shmem_exit(0): 1 before_shmem_exit callbacks to make
2022-07-21 09:31:05.719 CEST [32796] DEBUG:  shmem_exit(0): 5 on_shmem_exit callbacks to make
2022-07-21 09:31:05.719 CEST [32796] DEBUG:  proc_exit(0): 2 callbacks to make
2022-07-21 09:31:05.719 CEST [32796] DEBUG:  exit(0)
2022-07-21 09:31:05.719 CEST [32796] DEBUG:  shmem_exit(-1): 0 before_shmem_exit callbacks to make
2022-07-21 09:31:05.719 CEST [32796] DEBUG:  shmem_exit(-1): 0 on_shmem_exit callbacks to make
2022-07-21 09:31:05.719 CEST [32796] DEBUG:  proc_exit(-1): 0 callbacks to make
2022-07-21 09:31:05.720 CEST [32793] DEBUG:  reaping dead processes
2022-07-21 09:31:05.720 CEST [32800] DEBUG:  writing stats file "pg_stat/global.stat"
2022-07-21 09:31:05.720 CEST [32800] DEBUG:  writing stats file "pg_stat/db_16384.stat"
2022-07-21 09:31:05.720 CEST [32800] DEBUG:  removing temporary stats file "pg_stat_tmp/db_16384.stat"
2022-07-21 09:31:05.720 CEST [32800] DEBUG:  writing stats file "pg_stat/db_13445.stat"
2022-07-21 09:31:05.720 CEST [32800] DEBUG:  removing temporary stats file "pg_stat_tmp/db_13445.stat"
2022-07-21 09:31:05.720 CEST [32800] DEBUG:  writing stats file "pg_stat/db_0.stat"
2022-07-21 09:31:05.720 CEST [32800] DEBUG:  removing temporary stats file "pg_stat_tmp/db_0.stat"
2022-07-21 09:31:05.720 CEST [32800] DEBUG:  shmem_exit(-1): 0 before_shmem_exit callbacks to make
2022-07-21 09:31:05.720 CEST [32800] DEBUG:  shmem_exit(-1): 0 on_shmem_exit callbacks to make
2022-07-21 09:31:05.720 CEST [32800] DEBUG:  proc_exit(-1): 0 callbacks to make
2022-07-21 09:31:05.721 CEST [32793] DEBUG:  reaping dead processes
2022-07-21 09:31:05.721 CEST [32793] DEBUG:  shmem_exit(0): 0 before_shmem_exit callbacks to make
2022-07-21 09:31:05.721 CEST [32793] DEBUG:  shmem_exit(0): 5 on_shmem_exit callbacks to make
2022-07-21 09:31:05.721 CEST [32793] DEBUG:  cleaning up dynamic shared memory control segment with ID 1145400407
2022-07-21 09:31:05.723 CEST [32793] DEBUG:  proc_exit(0): 2 callbacks to make
2022-07-21 09:31:05.723 CEST [32793] LOG:  database system is shut down
2022-07-21 09:31:05.723 CEST [32793] DEBUG:  exit(0)
2022-07-21 09:31:05.723 CEST [32793] DEBUG:  shmem_exit(-1): 0 before_shmem_exit callbacks to make
2022-07-21 09:31:05.723 CEST [32793] DEBUG:  shmem_exit(-1): 0 on_shmem_exit callbacks to make
2022-07-21 09:31:05.723 CEST [32793] DEBUG:  proc_exit(-1): 0 callbacks to make
2022-07-21 09:31:05.724 CEST [32794] DEBUG:  logger shutting down
2022-07-21 09:31:05.724 CEST [32794] DEBUG:  shmem_exit(0): 0 before_shmem_exit callbacks to make
2022-07-21 09:31:05.724 CEST [32794] DEBUG:  shmem_exit(0): 0 on_shmem_exit callbacks to make
2022-07-21 09:31:05.724 CEST [32794] DEBUG:  proc_exit(0): 0 callbacks to make
2022-07-21 09:31:05.724 CEST [32794] DEBUG:  exit(0)
2022-07-21 09:31:05.724 CEST [32794] DEBUG:  shmem_exit(-1): 0 before_shmem_exit callbacks to make
2022-07-21 09:31:05.724 CEST [32794] DEBUG:  shmem_exit(-1): 0 on_shmem_exit callbacks to make
2022-07-21 09:31:05.724 CEST [32794] DEBUG:  proc_exit(-1): 0 callbacks to make
2022-07-21 09:31:07.137 CEST [33180] LOG:  starting PostgreSQL 13.7 (Debian 13.7-0+deb11u1) on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2022-07-21 09:31:07.137 CEST [33180] LOG:  listening on IPv4 address "0.0.0.0", port 15007
2022-07-21 09:31:07.137 CEST [33180] LOG:  listening on IPv6 address "::", port 15007
2022-07-21 09:31:07.140 CEST [33180] LOG:  listening on Unix socket "/var/run/postgresql/.s.PGSQL.15007"
2022-07-21 09:31:07.147 CEST [33192] LOG:  database system was shut down at 2022-07-21 09:31:05 CEST
2022-07-21 09:31:07.154 CEST [33180] LOG:  database system is ready to accept connections
2022-07-21 09:31:10.236 CEST [33180] LOG:  received fast shutdown request
2022-07-21 09:31:10.240 CEST [33180] LOG:  aborting any active transactions
2022-07-21 09:31:10.241 CEST [33180] LOG:  background worker "logical replication launcher" (PID 33198) exited with exit code 1
2022-07-21 09:31:10.241 CEST [33193] LOG:  shutting down
2022-07-21 09:31:10.261 CEST [33180] LOG:  database system is shut down

Best regards

Hi,
I try to compile and install on another VM. My first attemps failed despites correct compilation
and package generation.

But , i saw something.

When I compile with make , make dist I didn’t have the same error messages

and when I tested a make install I has a new message
lipq-fe.h was missing

I installed apt install libpq-dev

then force the last time of the day ( yesterday ) I compiled and generate with make dist + rpmbuild + alien my deb packages

I installed openpbs-server … and it worked !

Today on my prod server , I install the package and it works too …

I wonder why there are difference beetwen make depending of dist , install directive ?

“It’s not a bug , it’s a feature” :slight_smile:
Best Regards

1 Like