Forum OpenACS Q&A: Re: Error starting ACS
I changed the port and also initialized via CLI and on web I got the same error.
The output I got this when starting:
[rodrigo@rodrigo-vm ~]$ sudo /usr/bin/nsd -u nsadmin -t /etc/naviserver/config-oacs-5.9.1.tcl -f
[sudo] password for rodrigo:
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main-] Notice: OpenSSL 1.1.1h 22 Sep 2020 initialized
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main-] Notice: binder: started
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main-] Notice: nsd.tcl: starting to read config file...
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main-] Notice: Use Tcl thread library /usr/lib/naviserver/lib/thread2.8.5/libthread2.8.5.so
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main-] Notice: nsd.tcl: using threadsafe tcl: 1
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main-] Notice: nsd.tcl: finished reading config file.
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main-] Notice: nsmain: NaviServer/4.99.19 (tar-4.99.19) starting
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main-] Notice: nsmain: security info: uid=1001, euid=1001, gid=1001, egid=1001
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main-] Notice: nsmain: Tcl version: 8.6.10
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main-] Notice: nsmain: max files: soft limit 524288, hard limit 524288
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main-] Warning: nsmain: rl_cur (524288) > FD_SETSIZE (1024), select() calls should not be used
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: pool default: queueLength 90 low water 9 high water 90
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: nsd/init.tcl[openacs]: booting virtual server: Tcl system encoding: "utf-8"
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: modload: loading module nslog from file /usr/lib/naviserver/bin/nslog.so
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: nslog: opened '/usr/lib/openacs/log//access.log'
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: modload: loading module nsdb from file /usr/lib/naviserver/bin/nsdb.so
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: modload: loading module ns/db/driver/postgres from file /usr/lib/naviserver/bin/nsdbpg.so
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: nsdbpg: version 2.4 loaded, based on PostgreSQL 12.5 and libbpq 120005
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: modload: loading module libthread from file /usr/lib/naviserver/lib/thread2.8.5/libthread2.8.5.so
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: modload: loading module nssock from file /usr/lib/naviserver/bin/nssock.so
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: nssock:0: enable 0 spooler thread(s)
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: nssock:0: enable 2 writer thread(s) for downloads >= 4096 bytes, bufsize=8192 bytes, HTML streaming 0
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: nx::serializer version 2.3
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: XOTcl 2.3 loaded featuring: memcount 0 profile 0 memtrace 0 assertions 1 dtrace 0 development 0
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Using ns_cache based on NX 2.3
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: adp[openacs]: mapped {GET HEAD POST} /*.adp
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading OpenACS, rooted at /usr/lib/openacs
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Sourcing /usr/lib/openacs/packages/acs-bootstrap-installer/bootstrap.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: bootstrap begin encoding utf-8
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Bootstrap: sourcing /usr/lib/openacs/packages/acs-bootstrap-installer/tcl/00-proc-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Bootstrap: sourcing /usr/lib/openacs/packages/acs-bootstrap-installer/tcl/10-utilities-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Bootstrap: sourcing /usr/lib/openacs/packages/acs-bootstrap-installer/tcl/20-db-bootstrap-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Bootstrap: sourcing /usr/lib/openacs/packages/acs-bootstrap-installer/tcl/30-apm-load-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Bootstrap: sourcing /usr/lib/openacs/packages/acs-bootstrap-installer/tcl/40-db-query-dispatcher-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Database API: Default database (dbn) is: 'default'
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Database API: Using ALL database pools for OpenACS.
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Database API: The following pools are available for OpenACS: pool1 pool2 pool3
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: dbdrv: opening database 'postgres:localhost::oacs-5-9'
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: nsdbpg: Opening oacs-5-9 on localhost, port
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Error: nsdbpg(postgres): Could not connect to localhost::oacs-5-9: FATAL: role "nsadmin" does not exist
:
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Error: dbdrv: failed to open database 'postgres:localhost::oacs-5-9'
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Warning: Database API: couldn't allocate a handle from database pool "pool1".
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Error: Database API: RDBMS type could not be determined for any pool.
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Bootstrap: Loading acs-tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/00-database-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/10-charset-compat-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/30-xml-utils-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/acs-kernel-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/acs-permissions-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/acs-private-data-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/ad-functional-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/admin-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/adp-parser-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/aolserver-3-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/apm-file-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/apm-install-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/apm-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/apm-xml-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/application-data-link-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/application-link-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/callback-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/community-core-2-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/community-core-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/defs-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/deprecated-utilities-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/document-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/ds-stub-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/exception-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/form-processing-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/html-email-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/html-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/http-auth-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/http-client-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/image-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/install-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/json-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/membership-rel-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/memoize-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/navigation-callback-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/navigation-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/object-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/object-type-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/openacs-kernel-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/parameter-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/pdf-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/proxy-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/request-processor-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/security-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/server-cluster-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/set-operation-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/site-node-apm-integration-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/site-node-object-map-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Loading acs-tcl/tcl/site-nodes-procs.tcl
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: dbdrv: opening database 'postgres:localhost::oacs-5-9'
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: nsdbpg: Opening oacs-5-9 on localhost, port
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Error: nsdbpg(postgres): Could not connect to localhost::oacs-5-9: FATAL: role "nsadmin" does not exist
:
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Error: dbdrv: failed to open database 'postgres:localhost::oacs-5-9'
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Error: Error sourcing /usr/lib/openacs/packages/acs-tcl/tcl/site-nodes-procs.tcl:
: could not allocate 1 handle from pool "pool1"
: while executing
: "ns_db gethandle $pool"
: invoked from within
: "db_with_handle -dbn $dbn handle {
: set driver [ns_db driver $handle]
: }"
: (procedure "::nsf::procs::db_driverkey" line 33)
: invoked from within
: "db_driverkey """
: (file "/usr/lib/openacs/packages/acs-tcl/tcl/site-nodes-procs.tcl" line 71)
: invoked from within
: "source $__file "
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Error: Bootstrap: Server startup failed: Error during bootstrapping
: invalid database id: "handle"
: while executing
: "ns_db releasehandle $db"
: (procedure "::nsf::procs::db_release_unused_handles" line 20)
: invoked from within
: "db_release_unused_handles"
: (procedure "::nsf::procs::apm_bootstrap_load_libraries" line 29)
: invoked from within
: "apm_bootstrap_load_libraries -procs acs-tcl"
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Sourcing files for postload...
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Done.
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: Executing initialization code blocks...
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: QD=Postload files to load from www:
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: QD=Postload files to load from tcl:
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: sharedlib /usr/lib/naviserver/tcl/nslog is not a readable directory (ignored)
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: privatelib /usr/lib/openacs/tcl/nslog is not a readable directory (ignored)
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: sharedlib /usr/lib/naviserver/tcl/nsdb is not a readable directory (ignored)
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: privatelib /usr/lib/openacs/tcl/nsdb is not a readable directory (ignored)
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: sharedlib /usr/lib/naviserver/tcl/libthread is not a readable directory (ignored)
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: privatelib /usr/lib/openacs/tcl/libthread is not a readable directory (ignored)
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: sharedlib /usr/lib/naviserver/tcl/nssock is not a readable directory (ignored)
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: privatelib /usr/lib/openacs/tcl/nssock is not a readable directory (ignored)
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main:openacs-] Notice: update interpreter to epoch 1, trace deallocate, time 0.048274 secs
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main-] Notice: update interpreter to epoch 1, trace none, time 0.056690 secs
[16/Dec/2020:12:44:13][1466.7f7438f30640][-driver:nssock:0-] Notice: starting
[16/Dec/2020:12:44:13][1466.7f7438f30640][-driver:nssock:0-] Notice: nssock:0: listening on [127.0.0.1]:8888
[16/Dec/2020:12:44:13][1466.7f7438f30640][-driver:nssock:0-] Notice: driver: accepting connections
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main-] Notice: nsmain: NaviServer/4.99.19 (tar-4.99.19) running
[16/Dec/2020:12:44:13][1466.7f743b25c740][-main-] Notice: nsmain: security info: uid=1001, euid=1001, gid=1001, egid=1001
[16/Dec/2020:12:44:13][1466.7f743ac5d640][-sched-] Notice: sched: starting
[16/Dec/2020:12:44:13][1466.7f74277fe640][-writer0-] Notice: writer0: accepting connections
[16/Dec/2020:12:44:13][1466.7f7427fff640][-writer1-] Notice: writer1: accepting connections
[16/Dec/2020:12:44:13][1466.7f743b25c740][binder] Notice: binder: stopped
[16/Dec/2020:12:44:13][1466.7f7439f32640][-conn:openacs:default:0:0-] Notice: update interpreter to epoch 1, trace none, time 0.084142 secs
[16/Dec/2020:12:44:13][1466.7f7439f32640][-conn:openacs:default:0:0-] Notice: thread initialized (0.087292 secs)
[16/Dec/2020:12:44:13][1466.7f7439731640][-conn:openacs:default:1:0-] Notice: update interpreter to epoch 1, trace none, time 0.082766 secs
[16/Dec/2020:12:44:13][1466.7f7439731640][-conn:openacs:default:1:0-] Notice: thread initialized (0.089383 secs)