The following database pools generated errors:
* OpenACS could not allocate a handle from database pool "pool1".
Possible causes might include:
* The database is not running.
* The database driver has not been correctly installed.
* The datasource or database user/password are incorrect.
* You didn't define any database pools.
Followings are 5 kinds of information -- ps info., aolserver4.log, error.log, psql and postgresql.conf file
===========================================================================
1. ps
cs@debian:~$ ps axw |grep post
1588 ? S 0:01 /usr/lib/postgresql/8.4/bin/postgres -D /var/lib/postgresql/8.4/main -c config_file=/etc/postgresql/8.4/main/postgresql.conf
1652 ? Ss 0:00 postgres: writer process
1653 ? Ss 0:00 postgres: wal writer process
1654 ? Ss 0:00 postgres: autovacuum launcher process
1655 ? Ss 0:00 postgres: stats collector process
2201 ? Ss 0:00 postgres: www-data openacs [local] idle
2457 pts/1 S+ 0:00 grep post
===========================================================================
2. aolserver4.log
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: nsmain: AOLserver/4.5.1 starting
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: nsmain: security info: uid=33, euid=33, gid=33, egid=33
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: nsmain: max files: FD_SETSIZE = 1024, rl_cur = 1024, rl_max = 1024
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: fastpath[main]: mapped GET /
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: fastpath[main]: mapped HEAD /
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: fastpath[main]: mapped POST /
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: adp[main]: mapped GET /*.adp
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: adp[main]: mapped HEAD /*.adp
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: adp[main]: mapped POST /*.adp
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: adp[main]: mapped GET /*.html
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: adp[main]: mapped HEAD /*.html
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: adp[main]: mapped POST /*.html
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: modload: loading '/usr/lib/aolserver4/bin/nssock.so'
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: modload: loading '/usr/lib/aolserver4/bin/nslog.so'
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: nslog: opened '/var/log/aolserver4/aolserver4.log'
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: modload: loading '/usr/lib/aolserver4/bin/nscgi.so'
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Warning: nscgi: no such interps section: ns/interps/cgiinterps
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: nscgi: GET /cgi-bin -> /usr/lib/cgi-bin
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: nscgi: POST /cgi-bin -> /usr/lib/cgi-bin
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: nscgi: GET /*.py
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: nscgi: POST /*.pl
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: nscgi: POST /*.py
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: modload: loading '/usr/lib/aolserver4/bin/nsperm.so'
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: conf: [ns/server/main]enabletclpages = 0
[14/Sep/2011:10:24:22][1174.3075675840][-main-] Notice: default thread pool: minthreads 0 maxthreads 20 idle 0 current 0 maxconns 100 queued 0 timeout 120 spread 20
[14/Sep/2011:10:24:33][1174.3075675840][-main-] Notice: XOTcl version 1.6.6 loaded
[14/Sep/2011:10:24:34][1174.3075675840][-main-] Notice: nsmain: AOLserver/4.5.1 running
[14/Sep/2011:10:24:34][1174.3075675840][-main-] Notice: nsmain: security info: uid=33, euid=33, gid=33, egid=33
[14/Sep/2011:10:24:34][1174.3067063152][-sched-] Notice: sched: starting
[14/Sep/2011:10:24:34][1174.3075675840][-main-] Notice: driver: starting: nssock
[14/Sep/2011:10:24:34][1174.3066702704][-nssock:driver-] Notice: starting
[14/Sep/2011:10:24:34][1174.3066702704][-nssock:driver-] Error: nssock: failed to listen on 127.0.0.1:80: Address already in use
[14/Sep/2011:10:24:34][1174.3075675840][-main-] Fatal: could not start drivers
===========================================================================
3. error.log
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: nsmain: AOLserver/4.5.1 starting
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: nsmain: security info: uid=33, euid=33, gid=33, egid=33
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: nsmain: max files: FD_SETSIZE = 1024, rl_cur = 1024, rl_max = 1024
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: encoding: loaded: utf-8
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: fastpath[openacs]: mapped GET /
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: fastpath[openacs]: mapped HEAD /
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: fastpath[openacs]: mapped POST /
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: adp[openacs]: mapped GET /*.adp
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: adp[openacs]: mapped HEAD /*.adp
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: adp[openacs]: mapped POST /*.adp
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: modload: loading '/usr/lib/aolserver4/bin/nssock.so'
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: modload: loading '/usr/lib/aolserver4/bin/nslog.so'
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: nslog: opened '/usr/share/openacs/log/openacs.log'
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: modload: loading '/usr/lib/aolserver4/bin/nssha1.so'
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: modload: loading '/usr/lib/aolserver4/bin/nsdb.so'
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: modload: loading '/usr/lib/aolserver4/bin/nspostgres.so'
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: PostgreSQL loaded.
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: modload: loading '/usr/lib/tcltk/thread2.6.5/libthread2.6.5-aolserver.so'
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: conf: [ns/server/openacs]enabletclpages = 0
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: default thread pool: minthreads 5 maxthreads 10 idle 0 current 0 maxconns 100 queued 0 timeout 120 spread 20
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: XOTcl version 1.6.6 loaded
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Loading OpenACS, rooted at /usr/share/openacs
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Sourcing /usr/share/openacs/packages/acs-bootstrap-installer/bootstrap.tcl
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Bootstrap: sourcing /usr/share/openacs/packages/acs-bootstrap-installer/tcl/00-proc-procs.tcl
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Bootstrap: sourcing /usr/share/openacs/packages/acs-bootstrap-installer/tcl/10-utilities-procs.tcl
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Bootstrap: sourcing /usr/share/openacs/packages/acs-bootstrap-installer/tcl/20-db-bootstrap-procs.tcl
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Bootstrap: sourcing /usr/share/openacs/packages/acs-bootstrap-installer/tcl/30-apm-load-procs.tcl
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Bootstrap: sourcing /usr/share/openacs/packages/acs-bootstrap-installer/tcl/40-db-query-dispatcher-procs.tcl
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Database API: Default database (dbn) is: 'default'
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Database API: Using ALL database pools for OpenACS.
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Database API: The following pools are available for OpenACS: pool1 pool2 pool3
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: dbdrv: opening database 'postgres:localhost:5432:openacs'
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Opening openacs on localhost
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Ns_PgOpenDb(postgres): Openned connection to localhost:5432:openacs.
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: dbdrv: opening database 'postgres:localhost:5432:openacs'
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Opening openacs on localhost
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Ns_PgOpenDb(postgres): Openned connection to localhost:5432:openacs.
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: dbdrv: opening database 'postgres:localhost:5432:openacs'
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Opening openacs on localhost
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Ns_PgOpenDb(postgres): Openned connection to localhost:5432:openacs.
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Bootstrap: Loading acs-tcl
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Loading acs-tcl/tcl/00-database-procs-postgresql.tcl
[08/Sep/2011:11:42:45][5407.3076118208][-main-] Notice: Loading acs-tcl/tcl/00-database-procs.tcl
[08/Sep/2011:11:42:46][5407.3076118208][-main-] Notice: Loading acs-tcl/tcl/10-charset-compat-procs.tcl
[08/Sep/2011:11:42:46][5407.3076118208][-main-] Notice: Loading acs-tcl/tcl/30-xml-utils-procs.tcl
[08/Sep/2011:11:42:46][5407.3076118208][-main-] Notice: Loading acs-tcl/tcl/acs-kernel-procs.tcl
[08/Sep/2011:11:42:46][5407.3076118208][-main-] Notice: Loading acs-tcl/tcl/acs-permissions-procs.tcl
[08/Sep/2011:11:42:46][5407.3076118208][-main-] Notice: Loading acs-tcl/tcl/acs-private-data-procs.tcl
[08/Sep/2011:11:42:46][5407.3076118208][-main-] Notice: Loading acs-tcl/tcl/ad-functional-procs.tcl
[08/Sep/2011:11:42:46][5407.3076118208][-main-] Notice: Loading acs-tcl/tcl/admin-procs.tcl
[08/Sep/2011:11:42:46][5407.3076118208][-main-] Notice: Loading acs-tcl/tcl/adp-parser-procs.tcl
[08/Sep/2011:11:42:46][5407.3076118208][-main-] Notice: Loading acs-tcl/tcl/aolserver-3-procs.tcl
===========================================================================
4. psql
postgres@debian:~$
postgres@debian:~$ psql -l
List of databases
Name | Owner | Encoding | Collation | Ctype | Access privileges
-----------+----------+----------+-------------+-------------+-----------------------
openacs | www-data | UTF8 | en_US.UTF-8 | en_US.UTF-8 |
pool1 | postgres | UTF8 | en_US.UTF-8 | en_US.UTF-8 |
postgres | postgres | UTF8 | en_US.UTF-8 | en_US.UTF-8 |
service1 | postgres | UTF8 | en_US.UTF-8 | en_US.UTF-8 |
template0 | postgres | UTF8 | en_US.UTF-8 | en_US.UTF-8 | =c/postgres
: postgres=CTc/postgres
template1 | postgres | UTF8 | en_US.UTF-8 | en_US.UTF-8 | =c/postgres
: postgres=CTc/postgres
(6 rows)
postgres@debian:~$
===========================================================================
5. postgresql configuration file(postgresql.conf)
2011-09-15 16:45:04 KST LOG: database system was shut down at 2011-09-15 16:43:56 KST
2011-09-15 16:45:04 KST LOG: incomplete startup packet
2011-09-15 16:45:04 KST LOG: database system is ready to accept connections
2011-09-15 16:45:04 KST LOG: autovacuum launcher started
2011-09-15 16:54:07 KST LOG: unexpected EOF on client connection
2011-09-15 16:54:07 KST LOG: incomplete startup packet
2011-09-15 16:54:07 KST LOG: received fast shutdown request
2011-09-15 16:54:07 KST LOG: aborting any active transactions
2011-09-15 16:54:07 KST LOG: autovacuum launcher shutting down
2011-09-15 16:54:07 KST LOG: shutting down
2011-09-15 16:54:07 KST LOG: database system is shut down
2011-09-15 16:55:14 KST LOG: database system was shut down at 2011-09-15 16:54:07 KST
2011-09-15 16:55:14 KST LOG: database system is ready to accept connections
2011-09-15 16:55:14 KST LOG: autovacuum launcher started
2011-09-15 6:55:14 KST LOG: incomplete startup packet
3. Postgresql.conf file
#------------------------------------------------------------------------------
# FILE LOCATIONS
#------------------------------------------------------------------------------
# The default values of these variables are driven from the -D command-line
# option or PGDATA environment variable, represented here as ConfigDir.
data_directory = '/var/lib/postgresql/8.4/main' # use data in another directory
# (change requires restart)
hba_file = '/etc/postgresql/8.4/main/pg_hba.conf' # host-based authentication file
# (change requires restart)
ident_file = '/etc/postgresql/8.4/main/pg_ident.conf' # ident configuration file
# (change requires restart)
# If external_pid_file is not explicitly set, no extra PID file is written.
external_pid_file = '/var/run/postgresql/8.4-main.pid' # write an extra PID file
# (change requires restart)
#------------------------------------------------------------------------------
# CONNECTIONS AND AUTHENTICATION
#------------------------------------------------------------------------------
# - Connection Settings -
listen_addresses = 'localhost' # what IP address(es) to listen on;
# comma-separated list of addresses;
# defaults to 'localhost', '*' = all
# (change requires restart)
port = 5432 # (change requires restart)
max_connections = 100 # (change requires restart)
# Note: Increasing max_connections costs ~400 bytes of shared memory per
# connection slot, plus lock space (see max_locks_per_transaction).
#superuser_reserved_connections = 3 # (change requires restart)
unix_socket_directory = '/var/run/postgresql' # (change requires restart)
#unix_socket_group = '' # (change requires restart)
#unix_socket_permissions = 0777 # begin with 0 to use octal notation
# (change requires restart)
#bonjour_name = '' # defaults to the computer name
# (change requires restart)
# - Security and Authentication -
#authentication_timeout = 1min # 1s-600s
ssl = true # (change requires restart)
#ssl_ciphers = 'ALL:!ADH:!LOW:!EXP:!MD5:@STRENGTH' # allowed SSL ciphers
# (change requires restart)
#ssl_renegotiation_limit = 512MB # amount of data between renegotiations
#password_encryption = on
#db_user_namespace = off
# Kerberos and GSSAPI
#krb_server_keyfile = ''
#krb_srvname = 'postgres' # (Kerberos only)
#krb_caseins_users = off
# - TCP Keepalives -
# see "man 7 tcp" for details
#tcp_keepalives_idle = 0 # TCP_KEEPIDLE, in seconds;
# 0 selects the system default
#tcp_keepalives_interval = 0 # TCP_KEEPINTVL, in seconds;
# 0 selects the system default
#tcp_keepalives_count = 0 # TCP_KEEPCNT;
# 0 selects the system default
#------------------------------------------------------------------------------
# RESOURCE USAGE (except WAL)
#------------------------------------------------------------------------------
# - Memory -
shared_buffers = 28MB # min 128kB
# (change requires restart)
#temp_buffers = 8MB # min 800kB
#max_prepared_transactions = 0 # zero disables the feature
# (change requires restart)
# Note: Increasing max_prepared_transactions costs ~600 bytes of shared memory
# per transaction slot, plus lock space (see max_locks_per_transaction).
# It is not advisable to set max_prepared_transactions nonzero unless you
# actively intend to use prepared transactions.
#work_mem = 1MB # min 64kB
#maintenance_work_mem = 16MB # min 1MB
#max_stack_depth = 2MB # min 100kB
# - Kernel Resource Usage -
#max_files_per_process = 1000 # min 25
# (change requires restart)
#shared_preload_libraries = '' # (change requires restart)
# - Cost-Based Vacuum Delay -
#vacuum_cost_delay = 0ms # 0-100 milliseconds
#vacuum_cost_page_hit = 1 # 0-10000 credits
#vacuum_cost_page_miss = 10 # 0-10000 credits
#vacuum_cost_page_dirty = 20 # 0-10000 credits
#vacuum_cost_limit = 200 # 1-10000 credits
# - Background Writer -
#bgwriter_delay = 200ms # 10-10000ms between rounds
#bgwriter_lru_maxpages = 100 # 0-1000 max buffers written/round
#bgwriter_lru_multiplier = 2.0 # 0-10.0 multipler on buffers scanned/round
# - Asynchronous Behavior -
#effective_io_concurrency = 1 # 1-1000. 0 disables prefetching
Request notifications