Re: psql: FATAL: the database system is starting up

On Sat, Jun 1, 2019 at 6:05 PM Tom K <tomkcpr(at)gmail(dot)com> wrote:

>
>
> On Sat, Jun 1, 2019 at 5:51 PM Adrian Klaver <adrian(dot)klaver(at)aklaver(dot)com>
> wrote:
>
>> On 6/1/19 2:31 PM, Tom K wrote:
>> >
>> >
>>
>> >
>> > Spoke too soon. There's no tables when it's started without the
>> > recovery.conf file.
>>
>> Where there any errors in the start up?
>>
>
> Nothing I would discern as a startup error:
>
> [root(at)psql03 patroni]# sudo su - postgres
> Last login: Sat Jun 1 17:30:31 EDT 2019 on pts/1
> -bash-4.2$ /usr/pgsql-10/bin/postgres -D /data/patroni
> --config-file=/data/patroni/postgresql.conf
> --listen_addresses=192.168.0.118 --max_worker_processes=8
> --max_locks_per_transaction=64 --wal_level=replica
> --track_commit_timestamp=off --max_prepared_transactions=0 --port=5432
> --max_replication_slots=10 --max_connections=100 --hot_standby=on
> --cluster_name=postgres --wal_log_hints=on --max_wal_senders=10 -d 5
> 2019-06-01 17:41:27.477 EDT [15209] DEBUG: postgres: PostmasterMain:
> initial environment dump:
> 2019-06-01 17:41:27.477 EDT [15209] DEBUG:
> -----------------------------------------
> 2019-06-01 17:41:27.477 EDT [15209] DEBUG: XDG_SESSION_ID=219
> 2019-06-01 17:41:27.477 EDT [15209] DEBUG: HOSTNAME=
> psql03.nix.mds.xyz
> 2019-06-01 17:41:27.477 EDT [15209] DEBUG: SHELL=/bin/bash
> 2019-06-01 17:41:27.477 EDT [15209] DEBUG: TERM=xterm
> 2019-06-01 17:41:27.477 EDT [15209] DEBUG: HISTSIZE=1000
> 2019-06-01 17:41:27.477 EDT [15209] DEBUG: USER=postgres
> 2019-06-01 17:41:27.477 EDT [15209] DEBUG:
> LS_COLORS=rs=0:di=01;34:ln=01;36:mh=00:pi=40;33:so=01;35:do=01;35:bd=40;33;01:cd=40;33;01:or=40;31;01:mi=01;05;37;41:su=37;41:sg=30;43:ca=30;41:tw=30;42:ow=34;42:st=37;44:ex=01;32:*.tar=01;31:*.tgz=01;31:*.arc=01;31:*.arj=01;31:*.taz=01;31:*.lha=01;31:*.lz4=01;31:*.lzh=01;31:*.lzma=01;31:*.tlz=01;31:*.txz=01;31:*.tzo=01;31:*.t7z=01;31:*.zip=01;31:*.z=01;31:*.Z=01;31:*.dz=01;31:*.gz=01;31:*.lrz=01;31:*.lz=01;31:*.lzo=01;31:*.xz=01;31:*.bz2=01;31:*.bz=01;31:*.tbz=01;31:*.tbz2=01;31:*.tz=01;31:*.deb=01;31:*.rpm=01;31:*.jar=01;31:*.war=01;31:*.ear=01;31:*.sar=01;31:*.rar=01;31:*.alz=01;31:*.ace=01;31:*.zoo=01;31:*.cpio=01;31:*.7z=01;31:*.rz=01;31:*.cab=01;31:*.jpg=01;35:*.jpeg=01;35:*.gif=01;35:*.bmp=01;35:*.pbm=01;35:*.pgm=01;35:*.ppm=01;35:*.tga=01;35:*.xbm=01;35:*.xpm=01;35:*.tif=01;35:*.tiff=01;35:*.png=01;35:*.svg=01;35:*.svgz=01;35:*.mng=01;35:*.pcx=01;35:*.mov=01;35:*.mpg=01;35:*.mpeg=01;35:*.m2v=01;35:*.mkv=01;35:*.webm=01;35:*.ogm=01;35:*.mp4=01;35:*.m4v=01;35:*.mp4v=01;35:*.vob=01;35:*.qt=01;35:*.nuv=01;35:*.wmv=01;35:*.asf=01;35:*.rm=01;35:*.rmvb=01;35:*.flc=01;35:*.avi=01;35:*.fli=01;35:*.flv=01;35:*.gl=01;35:*.dl=01;35:*.xcf=01;35:*.xwd=01;35:*.yuv=01;35:*.cgm=01;35:*.emf=01;35:*.axv=01;35:*.anx=01;35:*.ogv=01;35:*.ogx=01;35:*.aac=01;36:*.au=01;36:*.flac=01;36:*.mid=01;36:*.midi=01;36:*.mka=01;36:*.mp3=01;36:*.mpc=01;36:*.ogg=01;36:*.ra=01;36:*.wav=01;36:*.axa=01;36:*.oga=01;36:*.spx=01;36:*.xspf=01;36:
> 2019-06-01 17:41:27.477 EDT [15209] DEBUG:
> MAIL=/var/spool/mail/postgres
> 2019-06-01 17:41:27.477 EDT [15209] DEBUG:
> PATH=/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/usr/pgsql-10/bin/
> 2019-06-01 17:41:27.477 EDT [15209] DEBUG: PWD=/var/lib/pgsql
> 2019-06-01 17:41:27.477 EDT [15209] DEBUG: LANG=en_US.UTF-8
> 2019-06-01 17:41:27.477 EDT [15209] DEBUG: HISTCONTROL=ignoredups
> 2019-06-01 17:41:27.477 EDT [15209] DEBUG: SHLVL=1
> 2019-06-01 17:41:27.477 EDT [15209] DEBUG: HOME=/var/lib/pgsql
> 2019-06-01 17:41:27.477 EDT [15209] DEBUG: LOGNAME=postgres
> 2019-06-01 17:41:27.478 EDT [15209] DEBUG:
> PGDATA=/var/lib/pgsql/10/data
> 2019-06-01 17:41:27.478 EDT [15209] DEBUG:
> LESSOPEN=||/usr/bin/lesspipe.sh %s
> 2019-06-01 17:41:27.478 EDT [15209] DEBUG:
> _=/usr/pgsql-10/bin/postgres
> 2019-06-01 17:41:27.478 EDT [15209] DEBUG:
> PGLOCALEDIR=/usr/pgsql-10/share/locale
> 2019-06-01 17:41:27.478 EDT [15209] DEBUG:
> PGSYSCONFDIR=/etc/sysconfig/pgsql
> 2019-06-01 17:41:27.478 EDT [15209] DEBUG: LC_COLLATE=en_US.UTF-8
> 2019-06-01 17:41:27.478 EDT [15209] DEBUG: LC_CTYPE=en_US.UTF-8
> 2019-06-01 17:41:27.478 EDT [15209] DEBUG: LC_MESSAGES=en_US.UTF-8
> 2019-06-01 17:41:27.478 EDT [15209] DEBUG: LC_MONETARY=C
> 2019-06-01 17:41:27.478 EDT [15209] DEBUG: LC_NUMERIC=C
> 2019-06-01 17:41:27.478 EDT [15209] DEBUG: LC_TIME=C
> 2019-06-01 17:41:27.478 EDT [15209] DEBUG:
> -----------------------------------------
> 2019-06-01 17:41:27.494 EDT [15209] DEBUG: registering background worker
> "logical replication launcher"
> 2019-06-01 17:41:27.495 EDT [15209] LOG: listening on IPv4 address
> "192.168.0.118", port 5432
> 2019-06-01 17:41:27.498 EDT [15209] LOG: listening on Unix socket
> "./.s.PGSQL.5432"
> 2019-06-01 17:41:27.499 EDT [15209] DEBUG: invoking
> IpcMemoryCreate(size=148545536)
> 2019-06-01 17:41:27.499 EDT [15209] DEBUG: mmap(148897792) with
> MAP_HUGETLB failed, huge pages disabled: Cannot allocate memory
> 2019-06-01 17:41:27.514 EDT [15209] DEBUG: SlruScanDirectory invoking
> callback on pg_notify/0000
> 2019-06-01 17:41:27.514 EDT [15209] DEBUG: removing file "pg_notify/0000"
> 2019-06-01 17:41:27.515 EDT [15209] DEBUG: dynamic shared memory system
> will support 288 segments
> 2019-06-01 17:41:27.515 EDT [15209] DEBUG: created dynamic shared memory
> control segment 834455595 (6928 bytes)
> 2019-06-01 17:41:27.518 EDT [15209] DEBUG: max_safe_fds = 985, usable_fds
> = 1000, already_open = 5
> 2019-06-01 17:41:27.519 EDT [15209] LOG: redirecting log output to
> logging collector process
> 2019-06-01 17:41:27.519 EDT [15209] HINT: Future log output will appear
> in directory "log".
> 2019-06-01 17:41:27.550 EDT [15210] DEBUG: logger shutting down
> 2019-06-01 17:41:27.550 EDT [15210] DEBUG: shmem_exit(0): 0
> before_shmem_exit callbacks to make
> 2019-06-01 17:41:27.550 EDT [15210] DEBUG: shmem_exit(0): 0 on_shmem_exit
> callbacks to make
> -bash-4.2$ 2019-06-01 17:41:27.550 EDT [15210] DEBUG: proc_exit(0): 0
> callbacks to make
> 2019-06-01 17:41:27.550 EDT [15210] DEBUG: exit(0)
> 2019-06-01 17:41:27.550 EDT [15210] DEBUG: shmem_exit(-1): 0
> before_shmem_exit callbacks to make
> 2019-06-01 17:41:27.550 EDT [15210] DEBUG: shmem_exit(-1): 0
> on_shmem_exit callbacks to make
> 2019-06-01 17:41:27.550 EDT [15210] DEBUG: proc_exit(-1): 0 callbacks to
> make
>
> -bash-4.2$
> -bash-4.2$
>
> This message:
>
> mmap(148897792) with MAP_HUGETLB failed, huge pages disabled: Cannot
> allocate memory
>
> doesn't seem to have been a problem. I doubled the memory on the VM but
> the message remained.
>
>
>> Are there databases in the clusters system(template1, postgres, etc) or
>> user?
>>
>
> No.
>

** Correction. There is postgres, template1 and template2 but none of the
other databases we had.

>
>
>>
>> Did you start against the correct PG_DATA directory?
>
>
> Yes.
>
>
>>
>>
>>
>>
>>
>> --
>> Adrian Klaver
>> adrian(dot)klaver(at)aklaver(dot)com
>>
>