# Strange problem starting pbs

**URL:** <https://community.openpbs.org/t/strange-problem-starting-pbs/3217>\
**Category:** Users/Site Administrators\
**Created:** [July 20, 2022, 11:46am UTC](https://community.openpbs.org/t/strange-problem-starting-pbs/3217 "2022-07-20T11:46:52Z")\
**Posts on this page:** 4\
**Page:** 1

<div class="post-metadata">

**Author:** ![jeromeleconte](https://avatars.discourse-cdn.com/v4/letter/j/6de8d8/32.png) [@jeromeleconte](https://community.openpbs.org/u/jeromeleconte)\
**Post date:** [July 20, 2022, 11:46am UTC](https://community.openpbs.org/t/strange-problem-starting-pbs/3217/1 "2022-07-20T11:46:52Z")

</div>

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 🙂 obviously

```auto
# 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

```auto
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 🙂

Best Regards

---

<div class="post-metadata">

**Author:** ![adarsh](https://avatars.discourse-cdn.com/v4/letter/a/f07891/32.png) [@adarsh](https://community.openpbs.org/u/adarsh)\
**Post date:** [July 21, 2022, 6:38am UTC](https://community.openpbs.org/t/strange-problem-starting-pbs/3217/2 "2022-07-21T06:38:36Z")

</div>

Could you please share the postgres logs and pbs server logs

---

<div class="post-metadata">

**Author:** ![jeromeleconte](https://avatars.discourse-cdn.com/v4/letter/j/6de8d8/32.png) [@jeromeleconte](https://community.openpbs.org/u/jeromeleconte)\
**Post date:** [July 21, 2022, 7:38am UTC](https://community.openpbs.org/t/strange-problem-starting-pbs/3217/3 "2022-07-21T07:38:03Z")

</div>

Hi,  
I made it this morning ( 9:25 … France 🙂 )  
restart with “service pbs restart” command  
pbs server\_log

```auto
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

```auto
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

```auto
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

---

<div class="post-metadata">

**Author:** ![jeromeleconte](https://avatars.discourse-cdn.com/v4/letter/j/6de8d8/32.png) [@jeromeleconte](https://community.openpbs.org/u/jeromeleconte)\
**Post date:** [July 22, 2022, 9:44am UTC](https://community.openpbs.org/t/strange-problem-starting-pbs/3217/4 "2022-07-22T09:44:31Z")

</div>

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” 🙂  
Best Regards